-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2024-07-11 10:22:32 CEST. -- Jul 11 10:21:09 volumio go-librespot[3052]: time="2024-07-11T10:21:09+02:00" level=debug msg="fetched chunk 5/16, size: 524288" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="handling play player command from 9107e985079ea5d5018ceeef7f073253e06d72bf" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=trace msg="fetched new page 0 with 323 items (list: 323)" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="loading track spotify:track:41zXlQxzTi6cGAjpOXyLYH (paused: false, position: 58ms)" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=trace msg="emitting websocket event: will_play" Jul 11 10:21:17 volumio volumio[910]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:41zXlQxzTi6cGAjpOXyLYH","play_origin":"playlist"}} Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:41zXlQxzTi6cGAjpOXyLYH" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="requested aes key for file 7f4d6c0524b8672995d6f3abb3323fc5a1ae9fcc, gid: 41zXlQxzTi6cGAjpOXyLYH" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="fetched first chunk of 15, total size is 7444680 bytes" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=trace msg="seek to 58ms (diff: 58ms, samples: 2557, bytes: 0)" Jul 11 10:21:17 volumio volumio[910]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 11 10:21:17 volumio volumio[910]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=info msg="loaded track \"idontwannabeyouanymore\" (uri: spotify:track:41zXlQxzTi6cGAjpOXyLYH, paused: false, position: 58ms, duration: 203569ms)" Jul 11 10:21:17 volumio volumio[910]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 11 10:21:17 volumio volumio[910]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=trace msg="emitting websocket event: metadata" Jul 11 10:21:17 volumio volumio[910]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:41zXlQxzTi6cGAjpOXyLYH","name":"idontwannabeyouanymore","artist_names":["Billie Eilish"],"album_name":"dont smile at me","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02a9f6c04ba168640b48aa5795","position":58,"duration":203569,"release_date":"year:2017 month:12 day:22","track_number":2,"disc_number":1}} Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="sending successful reply for delaer request" Jul 11 10:21:17 volumio go-librespot[3052]: time="2024-07-11T10:21:17+02:00" level=debug msg="fetched chunk 1/14, size: 524288" Jul 11 10:21:18 volumio go-librespot[3052]: time="2024-07-11T10:21:18+02:00" level=debug msg="fetched chunk 2/14, size: 524288" Jul 11 10:21:18 volumio go-librespot[3052]: time="2024-07-11T10:21:18+02:00" level=debug msg="fetched chunk 3/14, size: 524288" Jul 11 10:21:18 volumio go-librespot[3052]: time="2024-07-11T10:21:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 10:21:18 volumio go-librespot[3052]: time="2024-07-11T10:21:18+02:00" level=trace msg="emitting websocket event: playing" Jul 11 10:21:18 volumio volumio[910]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:41zXlQxzTi6cGAjpOXyLYH","play_origin":"playlist"}} Jul 11 10:21:18 volumio volumio[910]: SPOTIFY: PUSH STATE SPOTIFY Jul 11 10:21:18 volumio volumio[910]: SPOTIFY: {"status":"play","service":"spop","title":"idontwannabeyouanymore","artist":"Billie Eilish","album":"dont smile at me","albumart":"https://i.scdn.co/image/ab67616d00001e02a9f6c04ba168640b48aa5795","uri":"spotify:track:41zXlQxzTi6cGAjpOXyLYH","trackType":"spotify","seek":58,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 11 10:21:18 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jul 11 10:21:18 volumio volumio[910]: info: CoreStateMachine::pushState Jul 11 10:21:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:21:18 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jul 11 10:21:18 volumio volumio[910]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jul 11 10:21:18 volumio volumio[910]: SPOTIFY: PUSH STATE SPOTIFY Jul 11 10:21:18 volumio volumio[910]: SPOTIFY: {"status":"play","service":"spop","title":"idontwannabeyouanymore","artist":"Billie Eilish","album":"dont smile at me","albumart":"https://i.scdn.co/image/ab67616d00001e02a9f6c04ba168640b48aa5795","uri":"spotify:track:41zXlQxzTi6cGAjpOXyLYH","trackType":"spotify","seek":1058,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 11 10:21:18 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jul 11 10:21:18 volumio volumio[910]: info: CoreStateMachine::pushState Jul 11 10:21:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:21:18 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jul 11 10:21:18 volumio volumio[910]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jul 11 10:21:18 volumio volumio[910]: info: camilladsp spawned new process with pid 3240, instance 1, run: true Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="handling update_context player command from 9107e985079ea5d5018ceeef7f073253e06d72bf" Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="sending successful reply for delaer request" Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="handling update_context player command from 9107e985079ea5d5018ceeef7f073253e06d72bf" Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="sending successful reply for delaer request" Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="handling update_context player command from 9107e985079ea5d5018ceeef7f073253e06d72bf" Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 10:21:19 volumio go-librespot[3052]: time="2024-07-11T10:21:19+02:00" level=debug msg="sending successful reply for delaer request" Jul 11 10:21:30 volumio go-librespot[3052]: time="2024-07-11T10:21:30+02:00" level=debug msg="fetched chunk 4/14, size: 524288" Jul 11 10:21:46 volumio go-librespot[3052]: time="2024-07-11T10:21:46+02:00" level=debug msg="fetched chunk 5/14, size: 524288" Jul 11 10:22:00 volumio go-librespot[3052]: time="2024-07-11T10:22:00+02:00" level=debug msg="fetched chunk 6/14, size: 524288" Jul 11 10:22:14 volumio go-librespot[3052]: time="2024-07-11T10:22:14+02:00" level=debug msg="fetched chunk 7/14, size: 524288" Jul 11 10:22:25 volumio go-librespot[3052]: time="2024-07-11T10:22:25+02:00" level=debug msg="handling pause player command from 9107e985079ea5d5018ceeef7f073253e06d72bf" Jul 11 10:22:25 volumio go-librespot[3052]: time="2024-07-11T10:22:25+02:00" level=debug msg="pause track at 68178ms" Jul 11 10:22:25 volumio go-librespot[3052]: time="2024-07-11T10:22:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 10:22:25 volumio go-librespot[3052]: time="2024-07-11T10:22:25+02:00" level=debug msg="sending successful reply for delaer request" Jul 11 10:22:25 volumio go-librespot[3052]: time="2024-07-11T10:22:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 10:22:25 volumio go-librespot[3052]: time="2024-07-11T10:22:25+02:00" level=trace msg="emitting websocket event: paused" Jul 11 10:22:25 volumio volumio[910]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:41zXlQxzTi6cGAjpOXyLYH","play_origin":"playlist"}} Jul 11 10:22:25 volumio volumio[910]: SPOTIFY: PUSH STATE SPOTIFY Jul 11 10:22:25 volumio volumio[910]: SPOTIFY: {"status":"pause","service":"spop","title":"idontwannabeyouanymore","artist":"Billie Eilish","album":"dont smile at me","albumart":"https://i.scdn.co/image/ab67616d00001e02a9f6c04ba168640b48aa5795","uri":"spotify:track:41zXlQxzTi6cGAjpOXyLYH","trackType":"spotify","seek":68058,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 11 10:22:25 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jul 11 10:22:25 volumio volumio[910]: info: CoreStateMachine::pushState Jul 11 10:22:25 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:22:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jul 11 10:22:25 volumio volumio[910]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Jul 11 10:22:26 volumio volumio[910]: info: camilladsp spawned new process with pid 3288, instance 1, run: true Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 10:22:30 volumio volumio[910]: info: Discovery: Getting this device information Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 10:22:30 volumio volumio[910]: info: FusionDsp - Stopping FusionDsp service Jul 11 10:22:30 volumio volumio[910]: info: camilladsp stopping service pid 3288... Jul 11 10:22:30 volumio volumio[910]: info: camilladsp service terminated, instance 1 Jul 11 10:22:30 volumio volumio[910]: info: Disabling plugin fusiondsp Jul 11 10:22:30 volumio volumio[910]: info: Preparing to generate the ALSA configuration file Jul 11 10:22:30 volumio volumio[910]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 11 10:22:30 volumio volumio[910]: info: Reading ALSA contributions from plugins. Jul 11 10:22:30 volumio sudo[3298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jul 11 10:22:30 volumio volumio[910]: info: Asound.conf file written Jul 11 10:22:30 volumio sudo[3298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:30 volumio systemd[1]: Stopping FusionDsp Daemon... Jul 11 10:22:30 volumio volumio[2868]: Backend configuration: Jul 11 10:22:30 volumio volumio[2868]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jul 11 10:22:30 volumio volumio[2868]: active_config_txt: null Jul 11 10:22:30 volumio volumio[2868]: camilla_host: 127.0.0.1 Jul 11 10:22:30 volumio volumio[2868]: camilla_port: 9876 Jul 11 10:22:30 volumio volumio[2868]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jul 11 10:22:30 volumio volumio[2868]: config_dir: /data/configuration/audio_interface/fusiondsp Jul 11 10:22:30 volumio volumio[2868]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jul 11 10:22:30 volumio volumio[2868]: log_file: /tmp/camilladsp.log Jul 11 10:22:30 volumio volumio[2868]: on_get_active_config: null Jul 11 10:22:30 volumio volumio[2868]: on_set_active_config: null Jul 11 10:22:30 volumio volumio[2868]: port: 5011 Jul 11 10:22:30 volumio volumio[2868]: supported_capture_types: null Jul 11 10:22:30 volumio volumio[2868]: supported_playback_types: null Jul 11 10:22:30 volumio volumio[2868]: update_config_symlink: false Jul 11 10:22:30 volumio volumio[2868]: update_config_txt: false Jul 11 10:22:30 volumio volumio[2868]: ======== Running on http://0.0.0.0:5011 ======== Jul 11 10:22:30 volumio volumio[2868]: (Press CTRL+C to quit) Jul 11 10:22:30 volumio sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 11 10:22:30 volumio sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:30 volumio sudo[3301]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:30 volumio volumio[910]: info: Output device has changed, restarting MPD Jul 11 10:22:30 volumio sudo[3306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 10:22:30 volumio volumio[910]: info: Output device has changed, restarting Shairport Sync Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio sudo[3306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 10:22:30 volumio sudo[3306]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:30 volumio sudo[3308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 10:22:30 volumio sudo[3308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:30 volumio systemd[1]: fusiondsp.service: Succeeded. Jul 11 10:22:30 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 11 10:22:30 volumio sudo[3298]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:30 volumio systemd[1]: Stopping Music Player Daemon... Jul 11 10:22:30 volumio volumio[910]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 10:22:30 volumio volumio[910]: info: Done. Jul 11 10:22:30 volumio systemd[1]: mpd.service: Succeeded. Jul 11 10:22:30 volumio systemd[1]: Stopped Music Player Daemon. Jul 11 10:22:30 volumio volumio[910]: info: MPD Permissions set Jul 11 10:22:30 volumio volumio[910]: info: FusionDsp - Reporting Fusion DSP Disabled Jul 11 10:22:30 volumio volumio[910]: info: Removing fusiondspeq DSP Signal Path Element Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:30 volumio volumio[910]: info: Starting Shairport Sync Jul 11 10:22:30 volumio systemd[1]: Starting Music Player Daemon... Jul 11 10:22:30 volumio sudo[3325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 10:22:30 volumio sudo[3325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 10:22:30 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 11 10:22:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 10:22:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 10:22:30 volumio sudo[3325]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:30 volumio volumio[910]: info: Shairport-Sync Started Jul 11 10:22:31 volumio mpd[3314]: Jul 11 10:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 11 10:22:31 volumio systemd[1]: Started Music Player Daemon. Jul 11 10:22:31 volumio sudo[3308]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:31 volumio volumio[910]: error: updateQueue error: null Jul 11 10:22:31 volumio volumio[910]: info: Enabling plugin fusiondsp Jul 11 10:22:31 volumio volumio[910]: info: Loading plugin "fusiondsp"... Jul 11 10:22:31 volumio volumio[910]: info: Applying required configuration parameters for plugin fusiondsp Jul 11 10:22:31 volumio volumio[910]: info: Preparing to generate the ALSA configuration file Jul 11 10:22:31 volumio volumio[910]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 11 10:22:31 volumio volumio[910]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 11 10:22:31 volumio volumio[910]: info: Reading ALSA contributions from plugins. Jul 11 10:22:31 volumio volumio[910]: info: Asound.conf file written Jul 11 10:22:31 volumio sudo[3333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 11 10:22:31 volumio sudo[3333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:31 volumio sudo[3333]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:31 volumio volumio[910]: info: Output device has changed, restarting MPD Jul 11 10:22:31 volumio sudo[3338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 10:22:31 volumio sudo[3338]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:31 volumio volumio[910]: info: Output device has changed, restarting Shairport Sync Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 10:22:31 volumio sudo[3338]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:31 volumio sudo[3341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 10:22:31 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:31 volumio systemd[1]: Stopping Music Player Daemon... Jul 11 10:22:31 volumio volumio[910]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 10:22:31 volumio volumio[910]: info: PLUGIN START: fusiondsp Jul 11 10:22:31 volumio systemd[1]: mpd.service: Succeeded. Jul 11 10:22:31 volumio systemd[1]: Stopped Music Player Daemon. Jul 11 10:22:31 volumio volumio[910]: info: Loading i18n strings for locale it Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: FusionDsp - mixtype--------------------- Hardware Jul 11 10:22:31 volumio volumio[910]: info: Preparing to generate the ALSA configuration file Jul 11 10:22:31 volumio volumio[910]: info: Done. Jul 11 10:22:31 volumio volumio[910]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 11 10:22:31 volumio volumio[910]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 11 10:22:31 volumio volumio[910]: info: Reading ALSA contributions from plugins. Jul 11 10:22:31 volumio volumio[910]: info: MPD Permissions set Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: Starting Shairport Sync Jul 11 10:22:31 volumio volumio[910]: info: Asound.conf file unchanged, so no further update is needed Jul 11 10:22:31 volumio volumio[910]: info: Output device has changed, restarting MPD Jul 11 10:22:31 volumio systemd[1]: Starting Music Player Daemon... Jul 11 10:22:31 volumio sudo[3352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 10:22:31 volumio sudo[3352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 10:22:31 volumio sudo[3355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 10:22:31 volumio sudo[3355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:31 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 11 10:22:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 10:22:31 volumio sudo[3355]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 10:22:31 volumio volumio[910]: info: Output device has changed, restarting Shairport Sync Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 10:22:31 volumio sudo[3352]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:31 volumio sudo[3360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 10:22:31 volumio sudo[3360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:31 volumio volumio[910]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 10:22:31 volumio volumio[910]: info: MPD Permissions set Jul 11 10:22:31 volumio volumio[910]: info: Shairport-Sync Started Jul 11 10:22:31 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 11 10:22:31 volumio systemd[1]: mpd.service: Succeeded. Jul 11 10:22:31 volumio systemd[1]: Stopped Music Player Daemon. Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio systemd[1]: Starting Music Player Daemon... Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 10:22:31 volumio volumio[910]: info: Starting Shairport Sync Jul 11 10:22:31 volumio sudo[3372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 10:22:31 volumio sudo[3372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 10:22:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 10:22:31 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 11 10:22:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 10:22:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 10:22:31 volumio sudo[3372]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:31 volumio volumio[910]: info: Shairport-Sync Started Jul 11 10:22:31 volumio mpd[3366]: Jul 11 10:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 11 10:22:31 volumio systemd[1]: Started Music Player Daemon. Jul 11 10:22:31 volumio sudo[3360]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:31 volumio sudo[3341]: pam_unix(sudo:session): session closed for user root Jul 11 10:22:31 volumio volumio[910]: error: MPD error: The expression evaluated to a falsy value: Jul 11 10:22:31 volumio volumio[910]: assert.ok(self.idling) Jul 11 10:22:31 volumio volumio[910]: error: The expression evaluated to a falsy value: Jul 11 10:22:31 volumio volumio[910]: assert.ok(self.idling) Jul 11 10:22:31 volumio volumio[910]: error: updateQueue error: null Jul 11 10:22:31 volumio volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 10:22:32 volumio volumio[910]: TypeError: Cannot read property 'off' of undefined Jul 11 10:22:32 volumio volumio[910]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Jul 11 10:22:32 volumio volumio[910]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jul 11 10:22:32 volumio volumio[910]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jul 11 10:22:32 volumio volumio[910]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1531:29) Jul 11 10:22:32 volumio volumio[910]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1356:47) Jul 11 10:22:32 volumio volumio[910]: at Socket.emit (events.js:315:20) Jul 11 10:22:32 volumio volumio[910]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 11 10:22:32 volumio volumio[910]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 11 10:22:32 volumio volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 10:22:32 volumio sudo[3390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-11 10:21 Jul 11 10:22:32 volumio sudo[3390]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"