-- Logs begin at Thu 2019-02-14 13:11:58 +03, end at Tue 2024-07-23 17:34:58 +03. -- Jul 23 17:33:00 volumio ntpd[1212]: Soliciting pool server 2606:4700:f1::123 Jul 23 17:33:01 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:01 volumio systemd-udevd[3864]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:02 volumio wpa_supplicant[1295]: RRM: Ignoring radio measurement request: Not RRM network Jul 23 17:33:05 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:05 volumio systemd-udevd[3864]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:08 volumio volumio[1385]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 17:33:08 volumio sudo[3868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 17:33:08 volumio sudo[3868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:08 volumio sudo[3868]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:08 volumio volumio[1385]: info: MPD Permissions set Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio sudo[3872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 17:33:08 volumio sudo[3872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:08 volumio systemd[1]: musicservicesshield.service: Succeeded. Jul 23 17:33:08 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:08 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:08 volumio systemd[1]: Stopping Music Player Daemon... Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:33:08 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:08 volumio systemd[1]: mpd.service: Succeeded. Jul 23 17:33:08 volumio systemd[1]: Stopped Music Player Daemon. Jul 23 17:33:08 volumio systemd[1]: Starting Music Player Daemon... Jul 23 17:33:08 volumio sudo[3878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 17:33:08 volumio sudo[3878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:08 volumio sudo[3878]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:08 volumio mpd[3884]: Jul 23 17:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 17:33:08 volumio systemd[1]: Started Music Player Daemon. Jul 23 17:33:08 volumio sudo[3872]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:08 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:33:08 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:08 volumio volumio[3887]: cset: --> shielding system active with Jul 23 17:33:08 volumio volumio[3887]: cset: "system" cpuset of CPUSPEC(1-3) with 222 tasks running Jul 23 17:33:08 volumio volumio[3887]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 23 17:33:08 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:08 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:08 volumio systemd-udevd[3864]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:11 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:11 volumio systemd-udevd[3903]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:14 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:33:14 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:33:14 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::clearAddPlayTracks USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 17:33:14 volumio volumio[1385]: info: sendMpdCommand stop took 0 milliseconds Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 17:33:14 volumio volumio[1385]: info: Jul 23 17:33:14 volumio volumio[1385]: ---------------------------- MPD announces system playlist update Jul 23 17:33:14 volumio volumio[1385]: info: Ignoring MPD Status Update Jul 23 17:33:14 volumio volumio[1385]: info: sendMpdCommand clear took 0 milliseconds Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand add "USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav" Jul 23 17:33:14 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:33:14 volumio volumio[1385]: info: ------------------------------ 0ms Jul 23 17:33:14 volumio volumio[1385]: info: Jul 23 17:33:14 volumio volumio[1385]: ---------------------------- MPD announces system playlist update Jul 23 17:33:14 volumio volumio[1385]: info: Ignoring MPD Status Update Jul 23 17:33:14 volumio volumio[1385]: info: sendMpdCommand add "USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav" took 1 milliseconds Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:33:14 volumio volumio[1385]: info: ------------------------------ 1ms Jul 23 17:33:14 volumio volumio[1385]: info: Jul 23 17:33:14 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:14 volumio volumio[1385]: info: sendMpdCommand play took 1 milliseconds Jul 23 17:33:14 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:14 volumio volumio[1385]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 23 17:33:14 volumio volumio[1385]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 23 17:33:14 volumio volumio[1385]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 17:33:14 volumio volumio[1385]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 23 17:33:14 volumio volumio[1385]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 23 17:33:14 volumio volumio[1385]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 17:33:14 volumio volumio[1385]: info: Jul 23 17:33:14 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:14 volumio volumio[1385]: info: sendMpdCommand status took 20 milliseconds Jul 23 17:33:14 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:33:14 volumio volumio[1385]: info: sendMpdCommand status took 0 milliseconds Jul 23 17:33:14 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:33:14 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:14 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:14 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:14 volumio volumio[1385]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:33:14 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::syncState stateService play Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus stop Jul 23 17:33:14 volumio volumio[1385]: info: ------------------------------ 23ms Jul 23 17:33:14 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 23 17:33:14 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:33:14 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:14 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:14 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:14 volumio volumio[1385]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:33:14 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::syncState stateService play Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus play Jul 23 17:33:14 volumio volumio[1385]: info: Received an update from plugin. extracting info from payload Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:14 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:14 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:33:14 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:14 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:14 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:14 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:14 volumio volumio[1385]: info: ------------------------------ 9ms Jul 23 17:33:14 volumio volumio[1385]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 17:33:14 volumio volumio[1385]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 17:33:14 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:33:14 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:33:14 volumio sudo[3912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:33:14 volumio sudo[3912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:14 volumio sudo[3915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:33:14 volumio sudo[3915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:14 volumio systemd[1]: Started peppyspectrum Daemon. Jul 23 17:33:14 volumio sudo[3912]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:14 volumio sudo[3915]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:14 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:33:14 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:33:15 volumio volumio[3916]: No protocol specified Jul 23 17:33:15 volumio volumio[3916]: pygame 1.9.4.post1 Jul 23 17:33:15 volumio volumio[3916]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 23 17:33:15 volumio volumio[3916]: Traceback (most recent call last): Jul 23 17:33:15 volumio volumio[3916]: File "spectrum.py", line 715, in Jul 23 17:33:15 volumio volumio[3916]: pm = Spectrum(None, True) Jul 23 17:33:15 volumio volumio[3916]: File "spectrum.py", line 63, in __init__ Jul 23 17:33:15 volumio volumio[3916]: self.init_display() Jul 23 17:33:15 volumio volumio[3916]: File "spectrum.py", line 104, in init_display Jul 23 17:33:15 volumio volumio[3916]: pygame.display.init() Jul 23 17:33:15 volumio volumio[3916]: pygame.error: Unable to open /dev/fb1 Jul 23 17:33:15 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 23 17:33:15 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 23 17:33:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:15 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:15 volumio systemd-udevd[3903]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:16 volumio kernel: dma dma2chan2: BUG: dma2chan2, IRQ with no descriptors Jul 23 17:33:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:17 volumio volumio[1385]: info: Jul 23 17:33:17 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:17 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:17 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:17 volumio volumio[1385]: info: sendMpdCommand status took 1 milliseconds Jul 23 17:33:17 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:17 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:17 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:17 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:17 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:17 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:33:17 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:17 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:17 volumio volumio[1385]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 23 17:33:17 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:17 volumio volumio[1385]: info: CoreStateMachine::syncState stateService stop Jul 23 17:33:17 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus play Jul 23 17:33:17 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:17 volumio volumio[1385]: info: CoreStateMachine::stPlaybackTimer Jul 23 17:33:17 volumio volumio[1385]: info: ------------------------------ 9ms Jul 23 17:33:17 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:33:17 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status stop Jul 23 17:33:17 volumio volumio[1385]: info: touch_display: Setting screensaver timeout to 1200000 seconds. Jul 23 17:33:17 volumio sudo[3925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:33:17 volumio sudo[3925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:17 volumio sudo[3926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 23 17:33:17 volumio sudo[3926]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:18 volumio systemd[1]: Started peppyspectrum Daemon. Jul 23 17:33:18 volumio sudo[3926]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:18 volumio sudo[3925]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:18 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:33:18 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Jul 23 17:33:18 volumio volumio[1385]: Job for peppyspectrum.service canceled. Jul 23 17:33:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:18 volumio volumio[1385]: info: camilladsp spawned new process with pid 3931, instance 1, run: true Jul 23 17:33:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:18 volumio volumio[3929]: No protocol specified Jul 23 17:33:18 volumio volumio[3929]: pygame 1.9.4.post1 Jul 23 17:33:18 volumio volumio[3929]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 23 17:33:18 volumio volumio[3929]: Traceback (most recent call last): Jul 23 17:33:18 volumio volumio[3929]: File "spectrum.py", line 715, in Jul 23 17:33:18 volumio volumio[3929]: pm = Spectrum(None, True) Jul 23 17:33:18 volumio volumio[3929]: File "spectrum.py", line 63, in __init__ Jul 23 17:33:18 volumio volumio[3929]: self.init_display() Jul 23 17:33:18 volumio volumio[3929]: File "spectrum.py", line 104, in init_display Jul 23 17:33:18 volumio volumio[3929]: pygame.display.init() Jul 23 17:33:18 volumio volumio[3929]: pygame.error: Unable to open /dev/fb1 Jul 23 17:33:18 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 23 17:33:18 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 23 17:33:18 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:18 volumio systemd-udevd[3903]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:20 volumio wpa_supplicant[1295]: RRM: Ignoring radio measurement request: Not RRM network Jul 23 17:33:21 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:21 volumio systemd-udevd[3981]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:25 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:25 volumio systemd-udevd[3981]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:25 volumio volumio[1385]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 17:33:25 volumio sudo[3986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 17:33:25 volumio sudo[3986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:25 volumio sudo[3986]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 17:33:25 volumio sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:25 volumio systemd[1]: musicservicesshield.service: Succeeded. Jul 23 17:33:25 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:25 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:25 volumio systemd[1]: Stopping Music Player Daemon... Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:25 volumio volumio[1385]: info: MPD Permissions set Jul 23 17:33:25 volumio systemd[1]: mpd.service: Succeeded. Jul 23 17:33:25 volumio systemd[1]: Stopped Music Player Daemon. Jul 23 17:33:25 volumio systemd[1]: Starting Music Player Daemon... Jul 23 17:33:25 volumio sudo[3995]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 17:33:25 volumio sudo[3995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:25 volumio sudo[3995]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:25 volumio mpd[3997]: Jul 23 17:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 17:33:25 volumio systemd[1]: Started Music Player Daemon. Jul 23 17:33:25 volumio sudo[3990]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:25 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:33:25 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:26 volumio volumio[4000]: cset: --> shielding system active with Jul 23 17:33:26 volumio volumio[4000]: cset: "system" cpuset of CPUSPEC(1-3) with 223 tasks running Jul 23 17:33:26 volumio volumio[4000]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 23 17:33:26 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:28 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:33:28 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:33:28 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::clearAddPlayTracks USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 17:33:28 volumio volumio[1385]: info: sendMpdCommand stop took 0 milliseconds Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 17:33:28 volumio volumio[1385]: info: Jul 23 17:33:28 volumio volumio[1385]: ---------------------------- MPD announces system playlist update Jul 23 17:33:28 volumio volumio[1385]: info: Ignoring MPD Status Update Jul 23 17:33:28 volumio volumio[1385]: info: sendMpdCommand clear took 0 milliseconds Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand add "USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav" Jul 23 17:33:28 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:33:28 volumio volumio[1385]: info: ------------------------------ 1ms Jul 23 17:33:28 volumio volumio[1385]: info: Jul 23 17:33:28 volumio volumio[1385]: ---------------------------- MPD announces system playlist update Jul 23 17:33:28 volumio volumio[1385]: info: Ignoring MPD Status Update Jul 23 17:33:28 volumio volumio[1385]: info: sendMpdCommand add "USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav" took 1 milliseconds Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:33:28 volumio volumio[1385]: info: ------------------------------ 0ms Jul 23 17:33:28 volumio volumio[1385]: info: sendMpdCommand play took 0 milliseconds Jul 23 17:33:28 volumio volumio[1385]: info: Jul 23 17:33:28 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:28 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:28 volumio volumio[1385]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 23 17:33:28 volumio volumio[1385]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 23 17:33:28 volumio volumio[1385]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 17:33:28 volumio volumio[1385]: info: Jul 23 17:33:28 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:28 volumio volumio[1385]: info: sendMpdCommand status took 18 milliseconds Jul 23 17:33:28 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:33:28 volumio volumio[1385]: info: sendMpdCommand status took 0 milliseconds Jul 23 17:33:28 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:33:28 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:28 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:28 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:28 volumio volumio[1385]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:33:28 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::syncState stateService play Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus stop Jul 23 17:33:28 volumio volumio[1385]: info: ------------------------------ 19ms Jul 23 17:33:28 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 23 17:33:28 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:33:28 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:28 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:28 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:28 volumio volumio[1385]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:33:28 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::syncState stateService play Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus play Jul 23 17:33:28 volumio volumio[1385]: info: Received an update from plugin. extracting info from payload Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:28 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:28 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:33:28 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:28 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:28 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:28 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:28 volumio volumio[1385]: info: ------------------------------ 9ms Jul 23 17:33:28 volumio volumio[1385]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 17:33:28 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:28 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:33:28 volumio systemd-udevd[3981]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:28 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:33:28 volumio sudo[4022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:33:28 volumio sudo[4022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:28 volumio sudo[4025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:33:28 volumio sudo[4025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:28 volumio sudo[4025]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:28 volumio systemd[1]: Started peppyspectrum Daemon. Jul 23 17:33:28 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:33:28 volumio sudo[4022]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:28 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:33:28 volumio volumio[4027]: No protocol specified Jul 23 17:33:28 volumio volumio[4027]: pygame 1.9.4.post1 Jul 23 17:33:28 volumio volumio[4027]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 23 17:33:28 volumio volumio[4027]: Traceback (most recent call last): Jul 23 17:33:28 volumio volumio[4027]: File "spectrum.py", line 715, in Jul 23 17:33:28 volumio volumio[4027]: pm = Spectrum(None, True) Jul 23 17:33:28 volumio volumio[4027]: File "spectrum.py", line 63, in __init__ Jul 23 17:33:28 volumio volumio[4027]: self.init_display() Jul 23 17:33:28 volumio volumio[4027]: File "spectrum.py", line 104, in init_display Jul 23 17:33:28 volumio volumio[4027]: pygame.display.init() Jul 23 17:33:28 volumio volumio[4027]: pygame.error: Unable to open /dev/fb1 Jul 23 17:33:28 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 23 17:33:28 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 23 17:33:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:29 volumio volumio[1385]: info: camilladsp spawned new process with pid 4029, instance 1, run: true Jul 23 17:33:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:31 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:31 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:31 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:31 volumio volumio[1385]: info: camilladsp spawned new process with pid 4037, instance 1, run: true Jul 23 17:33:31 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:31 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:31 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:31 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:31 volumio systemd-udevd[4045]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:32 volumio volumio[1385]: info: Jul 23 17:33:32 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:32 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:32 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:32 volumio volumio[1385]: info: sendMpdCommand status took 0 milliseconds Jul 23 17:33:32 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:32 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:32 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:32 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:32 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:32 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:33:32 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:32 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:32 volumio volumio[1385]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 23 17:33:32 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:32 volumio volumio[1385]: info: CoreStateMachine::syncState stateService stop Jul 23 17:33:32 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus play Jul 23 17:33:32 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:32 volumio volumio[1385]: info: CoreStateMachine::stPlaybackTimer Jul 23 17:33:32 volumio volumio[1385]: info: ------------------------------ 5ms Jul 23 17:33:32 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:33:32 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status stop Jul 23 17:33:32 volumio sudo[4049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:33:32 volumio sudo[4049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:32 volumio sudo[4050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 23 17:33:32 volumio sudo[4050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:32 volumio volumio[1385]: info: touch_display: Setting screensaver timeout to 1200000 seconds. Jul 23 17:33:32 volumio systemd[1]: Started peppyspectrum Daemon. Jul 23 17:33:32 volumio sudo[4049]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:32 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:33:32 volumio systemd[1]: Stopping peppyspectrum Daemon... Jul 23 17:33:32 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Jul 23 17:33:32 volumio systemd[1]: peppyspectrum.service: Succeeded. Jul 23 17:33:32 volumio systemd[1]: Stopped peppyspectrum Daemon. Jul 23 17:33:32 volumio sudo[4050]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:32 volumio volumio[1385]: info: peppyspectrum Daemon Stop Jul 23 17:33:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:32 volumio volumio[1385]: info: camilladsp spawned new process with pid 4057, instance 1, run: true Jul 23 17:33:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:35 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:35 volumio systemd-udevd[4045]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:35 volumio volumio[1385]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 17:33:35 volumio sudo[4067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 17:33:35 volumio sudo[4067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:35 volumio sudo[4067]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio sudo[4071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 17:33:35 volumio sudo[4071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:35 volumio volumio[1385]: info: MPD Permissions set Jul 23 17:33:35 volumio systemd[1]: musicservicesshield.service: Succeeded. Jul 23 17:33:35 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:35 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:35 volumio systemd[1]: Stopping Music Player Daemon... Jul 23 17:33:35 volumio systemd[1]: mpd.service: Succeeded. Jul 23 17:33:35 volumio systemd[1]: Stopped Music Player Daemon. Jul 23 17:33:35 volumio systemd[1]: Starting Music Player Daemon... Jul 23 17:33:35 volumio sudo[4076]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 17:33:35 volumio sudo[4076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:35 volumio sudo[4076]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:36 volumio mpd[4078]: Jul 23 17:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 17:33:36 volumio systemd[1]: Started Music Player Daemon. Jul 23 17:33:36 volumio sudo[4071]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:36 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:33:36 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:36 volumio volumio[4081]: cset: --> shielding system active with Jul 23 17:33:36 volumio volumio[4081]: cset: "system" cpuset of CPUSPEC(1-3) with 223 tasks running Jul 23 17:33:36 volumio volumio[4081]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 23 17:33:36 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:38 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:38 volumio systemd-udevd[4045]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:38 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:33:38 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:33:38 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::clearAddPlayTracks USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 17:33:38 volumio volumio[1385]: info: sendMpdCommand stop took 1 milliseconds Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 17:33:38 volumio volumio[1385]: info: Jul 23 17:33:38 volumio volumio[1385]: ---------------------------- MPD announces system playlist update Jul 23 17:33:38 volumio volumio[1385]: info: Ignoring MPD Status Update Jul 23 17:33:38 volumio volumio[1385]: info: sendMpdCommand clear took 0 milliseconds Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand add "USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav" Jul 23 17:33:38 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:33:38 volumio volumio[1385]: info: ------------------------------ 0ms Jul 23 17:33:38 volumio volumio[1385]: info: Jul 23 17:33:38 volumio volumio[1385]: ---------------------------- MPD announces system playlist update Jul 23 17:33:38 volumio volumio[1385]: info: Ignoring MPD Status Update Jul 23 17:33:38 volumio volumio[1385]: info: sendMpdCommand add "USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav" took 0 milliseconds Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:33:38 volumio volumio[1385]: info: ------------------------------ 1ms Jul 23 17:33:38 volumio volumio[1385]: info: Jul 23 17:33:38 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:38 volumio volumio[1385]: info: sendMpdCommand play took 1 milliseconds Jul 23 17:33:38 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:38 volumio volumio[1385]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 23 17:33:38 volumio volumio[1385]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 23 17:33:38 volumio volumio[1385]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 17:33:38 volumio volumio[1385]: info: Jul 23 17:33:38 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:38 volumio volumio[1385]: info: sendMpdCommand status took 20 milliseconds Jul 23 17:33:38 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:33:38 volumio volumio[1385]: info: sendMpdCommand status took 0 milliseconds Jul 23 17:33:38 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:33:38 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:38 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:38 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:38 volumio volumio[1385]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:33:38 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::syncState stateService play Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus stop Jul 23 17:33:38 volumio volumio[1385]: info: ------------------------------ 22ms Jul 23 17:33:38 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 23 17:33:38 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:33:38 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:38 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:38 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:38 volumio volumio[1385]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:33:38 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::syncState stateService play Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus play Jul 23 17:33:38 volumio volumio[1385]: info: Received an update from plugin. extracting info from payload Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:38 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:38 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:33:38 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:38 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:38 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:38 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:38 volumio volumio[1385]: info: ------------------------------ 9ms Jul 23 17:33:38 volumio volumio[1385]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 17:33:38 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:33:38 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:33:38 volumio sudo[4103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:33:38 volumio sudo[4103]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:38 volumio sudo[4105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:33:38 volumio sudo[4105]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:38 volumio wpa_supplicant[1295]: RRM: Ignoring radio measurement request: Not RRM network Jul 23 17:33:38 volumio systemd[1]: Started peppyspectrum Daemon. Jul 23 17:33:38 volumio sudo[4103]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:38 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:33:38 volumio sudo[4105]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:38 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:33:39 volumio volumio[4108]: No protocol specified Jul 23 17:33:39 volumio volumio[4108]: pygame 1.9.4.post1 Jul 23 17:33:39 volumio volumio[4108]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 23 17:33:39 volumio volumio[4108]: Traceback (most recent call last): Jul 23 17:33:39 volumio volumio[4108]: File "spectrum.py", line 715, in Jul 23 17:33:39 volumio volumio[4108]: pm = Spectrum(None, True) Jul 23 17:33:39 volumio volumio[4108]: File "spectrum.py", line 63, in __init__ Jul 23 17:33:39 volumio volumio[4108]: self.init_display() Jul 23 17:33:39 volumio volumio[4108]: File "spectrum.py", line 104, in init_display Jul 23 17:33:39 volumio volumio[4108]: pygame.display.init() Jul 23 17:33:39 volumio volumio[4108]: pygame.error: Unable to open /dev/fb1 Jul 23 17:33:39 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 23 17:33:39 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 23 17:33:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:39 volumio volumio[1385]: info: camilladsp spawned new process with pid 4110, instance 1, run: true Jul 23 17:33:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:41 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:41 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:41 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:41 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:41 volumio systemd-udevd[4118]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:41 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:42 volumio volumio[1385]: info: CoreCommandRouter::volumioPause Jul 23 17:33:42 volumio volumio[1385]: info: CoreStateMachine::pause Jul 23 17:33:42 volumio volumio[1385]: info: CoreStateMachine::stPlaybackTimer Jul 23 17:33:42 volumio volumio[1385]: info: CoreStateMachine::servicePause Jul 23 17:33:42 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:42 volumio volumio[1385]: info: CoreCommandRouter::servicePause Jul 23 17:33:42 volumio volumio[1385]: info: ControllerMpd::pause Jul 23 17:33:42 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand pause Jul 23 17:33:42 volumio volumio[1385]: info: Jul 23 17:33:42 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:33:42 volumio volumio[1385]: info: sendMpdCommand pause took 1 milliseconds Jul 23 17:33:42 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:33:42 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:33:42 volumio volumio[1385]: info: sendMpdCommand status took 0 milliseconds Jul 23 17:33:42 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:33:42 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:33:42 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 23 17:33:42 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:33:42 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:33:42 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:33:42 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:42 volumio volumio[1385]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":288811,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:33:42 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:33:42 volumio volumio[1385]: info: CoreStateMachine::syncState stateService pause Jul 23 17:33:42 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus pause Jul 23 17:33:42 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:33:42 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:42 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:33:42 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:33:42 volumio volumio[1385]: info: CoreStateMachine::stPlaybackTimer Jul 23 17:33:42 volumio volumio[1385]: info: ------------------------------ 5ms Jul 23 17:33:42 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:42 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status pause Jul 23 17:33:42 volumio sudo[4121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 23 17:33:42 volumio sudo[4121]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:42 volumio volumio[1385]: info: camilladsp spawned new process with pid 4123, instance 1, run: true Jul 23 17:33:42 volumio sudo[4121]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:42 volumio volumio[1385]: info: peppyspectrum Daemon Stop Jul 23 17:33:42 volumio volumio[1385]: info: touch_display: Setting screensaver timeout to 1200000 seconds. Jul 23 17:33:42 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:42 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:33:45 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:45 volumio systemd-udevd[4118]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:45 volumio volumio[1385]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 17:33:45 volumio sudo[4137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 17:33:45 volumio sudo[4137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:45 volumio sudo[4137]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:45 volumio volumio[1385]: info: MPD Permissions set Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio sudo[4141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 17:33:45 volumio sudo[4141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:33:45 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:45 volumio systemd[1]: musicservicesshield.service: Succeeded. Jul 23 17:33:45 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:45 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:45 volumio systemd[1]: Stopping Music Player Daemon... Jul 23 17:33:45 volumio systemd[1]: mpd.service: Succeeded. Jul 23 17:33:45 volumio systemd[1]: Stopped Music Player Daemon. Jul 23 17:33:45 volumio systemd[1]: Starting Music Player Daemon... Jul 23 17:33:45 volumio sudo[4146]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 17:33:45 volumio sudo[4146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:45 volumio sudo[4146]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:46 volumio mpd[4148]: Jul 23 17:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 17:33:46 volumio systemd[1]: Started Music Player Daemon. Jul 23 17:33:46 volumio sudo[4141]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:46 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:33:46 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:46 volumio volumio[4151]: cset: --> shielding system active with Jul 23 17:33:46 volumio volumio[4151]: cset: "system" cpuset of CPUSPEC(1-3) with 224 tasks running Jul 23 17:33:46 volumio volumio[4151]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 23 17:33:46 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:48 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:48 volumio systemd-udevd[4118]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:51 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:51 volumio systemd-udevd[4167]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:55 volumio volumio[1385]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 17:33:55 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 17:33:55 volumio sudo[4170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 17:33:55 volumio sudo[4170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:55 volumio sudo[4170]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:55 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:55 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:55 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:55 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:55 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:55 volumio sudo[4174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 17:33:55 volumio sudo[4174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:55 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:33:55 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:33:55 volumio volumio[1385]: info: MPD Permissions set Jul 23 17:33:55 volumio systemd[1]: musicservicesshield.service: Succeeded. Jul 23 17:33:55 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:55 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:55 volumio systemd[1]: Stopping Music Player Daemon... Jul 23 17:33:55 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:55 volumio systemd[1]: mpd.service: Succeeded. Jul 23 17:33:55 volumio systemd[1]: Stopped Music Player Daemon. Jul 23 17:33:55 volumio systemd-udevd[4167]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:55 volumio systemd[1]: Starting Music Player Daemon... Jul 23 17:33:55 volumio sudo[4180]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 17:33:55 volumio sudo[4180]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:33:55 volumio sudo[4180]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:55 volumio mpd[4182]: Jul 23 17:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 17:33:55 volumio systemd[1]: Started Music Player Daemon. Jul 23 17:33:55 volumio sudo[4174]: pam_unix(sudo:session): session closed for user root Jul 23 17:33:55 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:33:55 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 23 17:33:55 volumio volumio[4185]: cset: --> shielding system active with Jul 23 17:33:55 volumio volumio[4185]: cset: "system" cpuset of CPUSPEC(1-3) with 224 tasks running Jul 23 17:33:55 volumio volumio[4185]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 23 17:33:55 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 23 17:33:56 volumio wpa_supplicant[1295]: RRM: Ignoring radio measurement request: Not RRM network Jul 23 17:33:57 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:33:57 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:33:57 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:33:57 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:57 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:33:57 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:57 volumio volumio[1385]: info: ControllerMpd::resume Jul 23 17:33:57 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:33:57 volumio volumio[1385]: info: sendMpdCommand play took 0 milliseconds Jul 23 17:33:58 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:33:58 volumio systemd-udevd[4167]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:33:59 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:33:59 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:33:59 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:33:59 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:59 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:33:59 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:59 volumio volumio[1385]: info: ControllerMpd::resume Jul 23 17:33:59 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:33:59 volumio volumio[1385]: info: sendMpdCommand play took 0 milliseconds Jul 23 17:33:59 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:33:59 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:33:59 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:33:59 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:59 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:33:59 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:33:59 volumio volumio[1385]: info: ControllerMpd::resume Jul 23 17:33:59 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:33:59 volumio volumio[1385]: info: sendMpdCommand play took 1 milliseconds Jul 23 17:34:00 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:34:00 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:34:00 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:34:00 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:00 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:34:00 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:00 volumio volumio[1385]: info: ControllerMpd::resume Jul 23 17:34:00 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:34:00 volumio volumio[1385]: info: sendMpdCommand play took 0 milliseconds Jul 23 17:34:01 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:34:01 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:34:01 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:34:01 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:01 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:34:01 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:01 volumio volumio[1385]: info: ControllerMpd::resume Jul 23 17:34:01 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:34:01 volumio volumio[1385]: info: sendMpdCommand play took 0 milliseconds Jul 23 17:34:01 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:34:01 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:34:01 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:34:01 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:01 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:34:01 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:01 volumio volumio[1385]: info: ControllerMpd::resume Jul 23 17:34:01 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:34:01 volumio volumio[1385]: info: sendMpdCommand play took 0 milliseconds Jul 23 17:34:01 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:01 volumio systemd-udevd[4167]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:02 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:34:02 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:34:02 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:34:02 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:02 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:34:02 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:02 volumio volumio[1385]: info: ControllerMpd::resume Jul 23 17:34:02 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:34:02 volumio volumio[1385]: info: sendMpdCommand play took 0 milliseconds Jul 23 17:34:03 volumio volumio[1385]: info: CoreCommandRouter::volumioNext Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::next Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::stop Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::updateTrackBlock Jul 23 17:34:03 volumio volumio[1385]: info: CorePlayQueue::getTrackBlock Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::stPlaybackTimer Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:34:03 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:03 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:34:03 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::serviceStop Jul 23 17:34:03 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:03 volumio volumio[1385]: info: CoreCommandRouter::serviceStop Jul 23 17:34:03 volumio volumio[1385]: info: ControllerMpd::stop Jul 23 17:34:03 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 17:34:03 volumio volumio[1385]: info: sendMpdCommand stop took 3 milliseconds Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:34:03 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:34:03 volumio volumio[1385]: info: CorePlayQueue::getTrack 1 Jul 23 17:34:03 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status stop Jul 23 17:34:03 volumio sudo[4204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 23 17:34:03 volumio sudo[4204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:03 volumio sudo[4204]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:03 volumio volumio[1385]: info: peppyspectrum Daemon Stop Jul 23 17:34:05 volumio volumio[1385]: info: CoreCommandRouter::volumioPlay Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::play index undefined Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 17:34:05 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::startPlaybackTimer Jul 23 17:34:05 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::clearAddPlayTracks USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 17:34:05 volumio volumio[1385]: info: sendMpdCommand stop took 1 milliseconds Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 17:34:05 volumio volumio[1385]: info: Jul 23 17:34:05 volumio volumio[1385]: ---------------------------- MPD announces system playlist update Jul 23 17:34:05 volumio volumio[1385]: info: Ignoring MPD Status Update Jul 23 17:34:05 volumio volumio[1385]: info: sendMpdCommand clear took 0 milliseconds Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand add "USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav" Jul 23 17:34:05 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:34:05 volumio volumio[1385]: info: ------------------------------ 0ms Jul 23 17:34:05 volumio volumio[1385]: info: Jul 23 17:34:05 volumio volumio[1385]: ---------------------------- MPD announces system playlist update Jul 23 17:34:05 volumio volumio[1385]: info: Ignoring MPD Status Update Jul 23 17:34:05 volumio volumio[1385]: info: sendMpdCommand add "USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav" took 0 milliseconds Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand play Jul 23 17:34:05 volumio volumio[1385]: info: ------------------------------ 0ms Jul 23 17:34:05 volumio volumio[1385]: info: Jul 23 17:34:05 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:34:05 volumio volumio[1385]: info: sendMpdCommand play took 2 milliseconds Jul 23 17:34:05 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:34:05 volumio volumio[1385]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 23 17:34:05 volumio volumio[1385]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 23 17:34:05 volumio volumio[1385]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 17:34:05 volumio volumio[1385]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 23 17:34:05 volumio volumio[1385]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 23 17:34:05 volumio volumio[1385]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 17:34:05 volumio volumio[1385]: info: Jul 23 17:34:05 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:34:05 volumio volumio[1385]: info: sendMpdCommand status took 22 milliseconds Jul 23 17:34:05 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:34:05 volumio volumio[1385]: info: sendMpdCommand status took 1 milliseconds Jul 23 17:34:05 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:34:05 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:34:05 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:34:05 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:05 volumio volumio[1385]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:34:05 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::syncState stateService play Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus stop Jul 23 17:34:05 volumio volumio[1385]: info: ------------------------------ 24ms Jul 23 17:34:05 volumio volumio[1385]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 23 17:34:05 volumio volumio[1385]: verbose: ControllerMpd::parseTrackInfo Jul 23 17:34:05 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:34:05 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:34:05 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:05 volumio volumio[1385]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aadyamai (Duet)","artist":"P Jayachandran, Sangeetha Madhav","album":"Aadyamaai","uri":"USB/MP3/ആദ്യമായ് 1999 സംഗീതം ബാലഭാസ്കർ ഈസ്റ്റ് കോസ്റ്റ് ഓഡിയോസ് Aadyamai Album 1999 Music Balabhaskar Eas Coas Audio/01. Aadyamai (Duet).wav","trackType":"wav"} Jul 23 17:34:05 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::syncState stateService play Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus play Jul 23 17:34:05 volumio volumio[1385]: info: Received an update from plugin. extracting info from payload Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:34:05 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:05 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:34:05 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:34:05 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:34:05 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:05 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:34:05 volumio volumio[1385]: info: ------------------------------ 6ms Jul 23 17:34:05 volumio volumio[1385]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 17:34:05 volumio volumio[1385]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 17:34:05 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:34:05 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:34:05 volumio sudo[4214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:34:05 volumio sudo[4214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:05 volumio sudo[4215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:34:05 volumio sudo[4215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:05 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:05 volumio systemd-udevd[4167]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:05 volumio systemd[1]: Started peppyspectrum Daemon. Jul 23 17:34:05 volumio sudo[4215]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:05 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:34:05 volumio sudo[4214]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:05 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:34:05 volumio volumio[4218]: No protocol specified Jul 23 17:34:05 volumio volumio[4218]: pygame 1.9.4.post1 Jul 23 17:34:05 volumio volumio[4218]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 23 17:34:05 volumio volumio[4218]: Traceback (most recent call last): Jul 23 17:34:05 volumio volumio[4218]: File "spectrum.py", line 715, in Jul 23 17:34:05 volumio volumio[4218]: pm = Spectrum(None, True) Jul 23 17:34:05 volumio volumio[4218]: File "spectrum.py", line 63, in __init__ Jul 23 17:34:05 volumio volumio[4218]: self.init_display() Jul 23 17:34:05 volumio volumio[4218]: File "spectrum.py", line 104, in init_display Jul 23 17:34:05 volumio volumio[4218]: pygame.display.init() Jul 23 17:34:05 volumio volumio[4218]: pygame.error: Unable to open /dev/fb1 Jul 23 17:34:05 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Jul 23 17:34:05 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 23 17:34:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:06 volumio volumio[1385]: info: camilladsp spawned new process with pid 4221, instance 1, run: true Jul 23 17:34:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio ntpd[1212]: Soliciting pool server 2606:4700:f1::1 Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio volumio[1385]: info: camilladsp spawned new process with pid 4229, instance 1, run: true Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio volumio[1385]: info: camilladsp spawned new process with pid 4237, instance 1, run: true Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:08 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:08 volumio systemd-udevd[4167]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:08 volumio volumio[1385]: info: camilladsp spawned new process with pid 4246, instance 1, run: true Jul 23 17:34:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:09 volumio volumio[1385]: info: Jul 23 17:34:09 volumio volumio[1385]: ---------------------------- MPD announces state update: player Jul 23 17:34:09 volumio volumio[1385]: info: ControllerMpd::getState Jul 23 17:34:09 volumio volumio[1385]: verbose: ControllerMpd::sendMpdCommand status Jul 23 17:34:09 volumio volumio[1385]: info: sendMpdCommand status took 0 milliseconds Jul 23 17:34:09 volumio volumio[1385]: verbose: ControllerMpd::parseState Jul 23 17:34:09 volumio volumio[1385]: info: ControllerMpd::pushState Jul 23 17:34:09 volumio volumio[1385]: info: CoreCommandRouter::servicePushState Jul 23 17:34:09 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:34:09 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:09 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:34:09 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:34:09 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:09 volumio volumio[1385]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 23 17:34:09 volumio volumio[1385]: verbose: CURRENT POSITION 0 Jul 23 17:34:09 volumio volumio[1385]: info: CoreStateMachine::syncState stateService stop Jul 23 17:34:09 volumio volumio[1385]: info: CoreStateMachine::syncState currentStatus play Jul 23 17:34:09 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:34:09 volumio volumio[1385]: info: CoreStateMachine::stPlaybackTimer Jul 23 17:34:09 volumio volumio[1385]: info: ------------------------------ 6ms Jul 23 17:34:09 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status play Jul 23 17:34:09 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status stop Jul 23 17:34:09 volumio sudo[4258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 23 17:34:09 volumio sudo[4258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:09 volumio volumio[1385]: info: touch_display: Setting screensaver timeout to 1200000 seconds. Jul 23 17:34:09 volumio sudo[4260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 23 17:34:09 volumio sudo[4260]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:09 volumio systemd[1]: Started peppyspectrum Daemon. Jul 23 17:34:09 volumio systemd[1]: Stopping peppyspectrum Daemon... Jul 23 17:34:09 volumio sudo[4258]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:09 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Jul 23 17:34:09 volumio systemd[1]: peppyspectrum.service: Succeeded. Jul 23 17:34:09 volumio systemd[1]: Stopped peppyspectrum Daemon. Jul 23 17:34:09 volumio volumio[1385]: info: peppyspectrum Daemon Started Jul 23 17:34:09 volumio sudo[4260]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:09 volumio volumio[1385]: info: peppyspectrum Daemon Stop Jul 23 17:34:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:09 volumio volumio[1385]: info: camilladsp spawned new process with pid 4264, instance 1, run: true Jul 23 17:34:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:09 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:11 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:11 volumio systemd-udevd[4167]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:12 volumio volumio[1385]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 17:34:12 volumio sudo[4274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 17:34:12 volumio sudo[4274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:12 volumio sudo[4274]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:12 volumio volumio[1385]: info: MPD Permissions set Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio sudo[4278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 17:34:12 volumio sudo[4278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:12 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:12 volumio systemd[1]: musicservicesshield.service: Succeeded. Jul 23 17:34:12 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 23 17:34:12 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 23 17:34:12 volumio systemd[1]: Stopping Music Player Daemon... Jul 23 17:34:12 volumio systemd[1]: mpd.service: Succeeded. Jul 23 17:34:12 volumio systemd[1]: Stopped Music Player Daemon. Jul 23 17:34:12 volumio systemd[1]: Starting Music Player Daemon... Jul 23 17:34:12 volumio sudo[4283]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 17:34:12 volumio sudo[4283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:12 volumio sudo[4283]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:13 volumio mpd[4285]: Jul 23 17:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 17:34:13 volumio systemd[1]: Started Music Player Daemon. Jul 23 17:34:13 volumio sudo[4278]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:13 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:34:13 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 23 17:34:13 volumio volumio[4288]: cset: --> shielding system active with Jul 23 17:34:13 volumio volumio[4288]: cset: "system" cpuset of CPUSPEC(1-3) with 224 tasks running Jul 23 17:34:13 volumio volumio[4288]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 23 17:34:13 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 23 17:34:15 volumio wpa_supplicant[1295]: RRM: Ignoring radio measurement request: Not RRM network Jul 23 17:34:15 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:15 volumio systemd-udevd[4303]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:18 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:18 volumio systemd-udevd[4303]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:21 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:21 volumio systemd-udevd[4303]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:25 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:25 volumio systemd-udevd[4349]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:28 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:28 volumio systemd-udevd[4349]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:31 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:31 volumio systemd-udevd[4349]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:33 volumio wpa_supplicant[1295]: RRM: Ignoring radio measurement request: Not RRM network Jul 23 17:34:35 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:35 volumio systemd-udevd[4353]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:38 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:38 volumio systemd-udevd[4353]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:41 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:41 volumio systemd-udevd[4353]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:45 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:45 volumio systemd-udevd[4359]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:48 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:48 volumio systemd-udevd[4359]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:51 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:51 volumio systemd-udevd[4359]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:54 volumio volumio[1385]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 23 17:34:54 volumio volumio[1385]: info: Preparing to save Alsa Options, stopping services first Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::volumioGetState Jul 23 17:34:54 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::volumioPause Jul 23 17:34:54 volumio volumio[1385]: info: CoreStateMachine::pause Jul 23 17:34:54 volumio volumio[1385]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":true,"i2sid":{"value":"hifiberry-amp","label":"HiFiBerry Amp"}} Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: Enabling I2S DAC: HiFiBerry Amp Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio sudo[4364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 23 17:34:54 volumio sudo[4364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio sudo[4364]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio volumio[1385]: info: No Overlays Loaded Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio sudo[4367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-amp Jul 23 17:34:54 volumio sudo[4367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2s@a0000/status Jul 23 17:34:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/status Jul 23 17:34:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Jul 23 17:34:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Jul 23 17:34:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Jul 23 17:34:54 volumio volumio[1385]: warn: Unable to locate the device HDMI 0 Out a reboot may be required. Jul 23 17:34:54 volumio sudo[4367]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: Found match in i2s Card Database: setting mixer Master for card HiFiBerry Amp Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 23 17:34:54 volumio volumio[1385]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Amp Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 23 17:34:54 volumio volumio[1385]: info: Disabling external Volume Control Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jul 23 17:34:54 volumio volumio[1385]: info: Preparing to generate the ALSA configuration file Jul 23 17:34:54 volumio volumio[1385]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 23 17:34:54 volumio volumio[1385]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 23 17:34:54 volumio volumio[1385]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Jul 23 17:34:54 volumio volumio[1385]: info: I2S Param [object Object] successfully enabled Jul 23 17:34:54 volumio volumio[1385]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 23 17:34:54 volumio volumio[1385]: info: Reading ALSA contributions from plugins. Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 23 17:34:54 volumio volumio[1385]: info: Getting Alsa Cards List without I2S DAC Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jul 23 17:34:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 23 17:34:54 volumio volumio[1385]: info: VolumeController:: Volume=undefined Mute =false Jul 23 17:34:54 volumio volumio[1385]: info: CoreStateMachine::pushState Jul 23 17:34:54 volumio volumio[1385]: info: CorePlayQueue::getTrack 0 Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::volumioPushState Jul 23 17:34:54 volumio volumio[1385]: info: Asound.conf file written Jul 23 17:34:54 volumio sudo[4408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 23 17:34:54 volumio sudo[4408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio sudo[4408]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio volumio[1385]: No state is present for card DAC Jul 23 17:34:54 volumio volumio[1385]: Found hardware: "I-Sabre_Q2M_DAC" "" "" "" "" Jul 23 17:34:54 volumio volumio[1385]: Hardware is initialized using a generic method Jul 23 17:34:54 volumio volumio[1385]: No state is present for card DAC Jul 23 17:34:54 volumio volumio[1385]: info: Output device has changed, restarting MPD Jul 23 17:34:54 volumio volumio[1385]: info: Output device has changed, restarting Shairport Sync Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio sudo[4415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 17:34:54 volumio sudo[4415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio sudo[4416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 17:34:54 volumio sudo[4416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio sudo[4415]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio systemd[1]: musicservicesshield.service: Succeeded. Jul 23 17:34:54 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 23 17:34:54 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 23 17:34:54 volumio systemd[1]: Stopping Music Player Daemon... Jul 23 17:34:54 volumio volumio[1385]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 23 17:34:54 volumio volumio[1385]: info: Output device has changed, restarting MPD Jul 23 17:34:54 volumio systemd[1]: mpd.service: Succeeded. Jul 23 17:34:54 volumio systemd[1]: Stopped Music Player Daemon. Jul 23 17:34:54 volumio volumio[1385]: info: Output device has changed, restarting Shairport Sync Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio sudo[4424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 17:34:54 volumio sudo[4424]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio systemd[1]: Starting Music Player Daemon... Jul 23 17:34:54 volumio sudo[4424]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio sudo[4427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 17:34:54 volumio sudo[4427]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 23 17:34:54 volumio systemd[1]: mpd.service: Succeeded. Jul 23 17:34:54 volumio systemd[1]: Stopped Music Player Daemon. Jul 23 17:34:54 volumio systemd[1]: Starting Music Player Daemon... Jul 23 17:34:54 volumio volumio[1385]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 23 17:34:54 volumio volumio[1385]: info: MPD Permissions set Jul 23 17:34:54 volumio volumio[1385]: info: MPD Permissions set Jul 23 17:34:54 volumio volumio[1385]: info: PeppySpectrum ---peppyspectrum status stop Jul 23 17:34:54 volumio sudo[4432]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 17:34:54 volumio sudo[4432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio sudo[4432]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio sudo[4436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 23 17:34:54 volumio sudo[4436]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio sudo[4436]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 17:34:54 volumio volumio[1385]: info: peppyspectrum Daemon Stop Jul 23 17:34:54 volumio volumio[1385]: info: Starting Shairport Sync Jul 23 17:34:54 volumio volumio[1385]: info: Starting Shairport Sync Jul 23 17:34:54 volumio sudo[4449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 23 17:34:54 volumio sudo[4449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio sudo[4448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 23 17:34:54 volumio sudo[4448]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 17:34:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 23 17:34:54 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 23 17:34:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 23 17:34:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 23 17:34:54 volumio sudo[4449]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 23 17:34:54 volumio volumio[1385]: info: Shairport-Sync Started Jul 23 17:34:54 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 23 17:34:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 23 17:34:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 23 17:34:54 volumio sudo[4448]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:54 volumio volumio[1385]: info: Shairport-Sync Started Jul 23 17:34:55 volumio mpd[4435]: Jul 23 17:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 17:34:55 volumio systemd[1]: Started Music Player Daemon. Jul 23 17:34:55 volumio sudo[4427]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:55 volumio sudo[4416]: pam_unix(sudo:session): session closed for user root Jul 23 17:34:55 volumio volumio[1385]: error: MPD error: The expression evaluated to a falsy value: Jul 23 17:34:55 volumio volumio[1385]: assert.ok(self.idling) Jul 23 17:34:55 volumio volumio[1385]: error: The expression evaluated to a falsy value: Jul 23 17:34:55 volumio volumio[1385]: assert.ok(self.idling) Jul 23 17:34:55 volumio volumio[1385]: error: updateQueue error: null Jul 23 17:34:55 volumio wpa_supplicant[1295]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 23 17:34:55 volumio systemd-udevd[4385]: Process '/sbin/crda' failed with exit code 255. Jul 23 17:34:55 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 23 17:34:55 volumio volumio[4462]: cset: --> shielding system active with Jul 23 17:34:55 volumio volumio[4462]: cset: "system" cpuset of CPUSPEC(1-3) with 224 tasks running Jul 23 17:34:55 volumio volumio[4462]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 23 17:34:55 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 23 17:34:56 volumio wpa_supplicant[1295]: RRM: Ignoring radio measurement request: Not RRM network Jul 23 17:34:58 volumio volumio[1385]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jul 23 17:34:58 volumio volumio[1385]: info: PLUGIN onReboot : networkfs Jul 23 17:34:58 volumio volumio[1385]: info: PLUGIN onReboot : audiophonicsonoff Jul 23 17:34:58 volumio volumio[1385]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 17:34:58 volumio volumio[1385]: TypeError: Cannot read property 'writeSync' of undefined Jul 23 17:34:58 volumio volumio[1385]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jul 23 17:34:58 volumio volumio[1385]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jul 23 17:34:58 volumio volumio[1385]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jul 23 17:34:58 volumio volumio[1385]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 23 17:34:58 volumio volumio[1385]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 23 17:34:58 volumio volumio[1385]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jul 23 17:34:58 volumio volumio[1385]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Jul 23 17:34:58 volumio volumio[1385]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:857:33) Jul 23 17:34:58 volumio volumio[1385]: at Socket.emit (events.js:315:20) Jul 23 17:34:58 volumio volumio[1385]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 23 17:34:58 volumio volumio[1385]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 23 17:34:58 volumio volumio[1385]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 17:34:58 volumio sudo[4487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-23 17:33 Jul 23 17:34:58 volumio sudo[4487]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"