-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-08-31 12:19:01 CEST. -- Aug 31 12:18:00 volumio volumio[2753]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 31 12:18:00 volumio volumio[2753]: info: Preparing to save Alsa Options, stopping services first Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::volumioGetState Aug 31 12:18:00 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::volumioPause Aug 31 12:18:00 volumio volumio[2753]: info: CoreStateMachine::pause Aug 31 12:18:00 volumio volumio[2753]: info: CoreStateMachine::stPlaybackTimer Aug 31 12:18:00 volumio volumio[2753]: info: CoreStateMachine::servicePause Aug 31 12:18:00 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::servicePause Aug 31 12:18:00 volumio volumio[2753]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 12:18:00 volumio volumio[2753]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Headphones"},"i2s":true,"i2sid":{"value":"justboom-digi","label":"JustBoom Digi Boards"}} Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: warn: Unable to locate the device Headphones a reboot may be required. Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: Invalid card number. Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 31 12:18:00 volumio volumio[2753]: info: Updating Volume Controller Parameters: Device: 2 Name: JustBoom Digi Boards Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 31 12:18:00 volumio volumio[2753]: info: Disabling external Volume Control Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 12:18:00 volumio volumio[2753]: info: Preparing to generate the ALSA configuration file Aug 31 12:18:00 volumio volumio[2753]: info: sendMpdCommand pause took 166 milliseconds Aug 31 12:18:00 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::servicePushState Aug 31 12:18:00 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:00 volumio volumio[2753]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002KF4.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Black Water","title":"Black Water","artist":"The Doobie Brothers","album":"What Were Once Vices Are Now Habits","streaming":true,"disableUiControls":true,"duration":72,"seek":47527,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 31 12:18:00 volumio volumio[2753]: verbose: CURRENT POSITION 0 Aug 31 12:18:00 volumio volumio[2753]: info: CoreStateMachine::syncState stateService pause Aug 31 12:18:00 volumio volumio[2753]: info: CoreStateMachine::syncState currentStatus pause Aug 31 12:18:00 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:00 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:00 volumio volumio[2753]: info: CoreStateMachine::stPlaybackTimer Aug 31 12:18:00 volumio volumio[2753]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 31 12:18:00 volumio volumio[2753]: info: VolumeController:: Volume=undefined Mute =false Aug 31 12:18:00 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:00 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 12:18:00 volumio volumio[2753]: info: Getting Alsa Cards List without I2S DAC Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: Invalid card number. Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 12:18:00 volumio volumio[2753]: info: touch_display: Setting screensaver timeout to 60 seconds. Aug 31 12:18:00 volumio volumio[2753]: info: Asound.conf file unchanged, so no further update is needed Aug 31 12:18:00 volumio volumio[2753]: info: Output device has changed, restarting MPD Aug 31 12:18:00 volumio sudo[6202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 12:18:00 volumio sudo[6202]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:00 volumio volumio[2753]: info: Output device has changed, restarting Shairport Sync Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:00 volumio sudo[6202]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:00 volumio sudo[6205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 12:18:00 volumio sudo[6205]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:00 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 12:18:00 volumio volumio[2753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 12:18:00 volumio systemd[1]: mpd.service: Succeeded. Aug 31 12:18:00 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 12:18:00 volumio volumio[2753]: info: MPD Permissions set Aug 31 12:18:00 volumio systemd[1]: Starting Music Player Daemon... Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:00 volumio volumio[2753]: info: touch_display: Setting screensaver timeout to 60 seconds. Aug 31 12:18:00 volumio volumio[2753]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 31 12:18:00 volumio volumio[2753]: info: Starting Shairport Sync Aug 31 12:18:00 volumio sudo[6210]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 12:18:00 volumio sudo[6210]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:00 volumio sudo[6221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 12:18:00 volumio sudo[6221]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:00 volumio sudo[6210]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 12:18:00 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 31 12:18:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 12:18:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 12:18:00 volumio sudo[6221]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:00 volumio volumio[2753]: info: Shairport-Sync Started Aug 31 12:18:01 volumio mpd[6224]: Aug 31 12:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 12:18:01 volumio systemd[1]: Started Music Player Daemon. Aug 31 12:18:01 volumio sudo[6205]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:01 volumio volumio[2753]: error: updateQueue error: null Aug 31 12:18:04 volumio volumio[2753]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 31 12:18:04 volumio volumio[2753]: info: CoreStateMachine::getcurrentVolume Aug 31 12:18:04 volumio volumio[2753]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 12:18:04 volumio volumio[2753]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 31 12:18:04 volumio volumio[2753]: info: VolumeController:: Volume=undefined Mute =false Aug 31 12:18:04 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:04 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:04 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:04 volumio volumio[2753]: info: CoreStateMachine::updateTrackBlock Aug 31 12:18:04 volumio volumio[2753]: info: CorePlayQueue::getTrackBlock Aug 31 12:18:04 volumio volumio[2753]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 12:18:04 volumio volumio[2753]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 31 12:18:04 volumio volumio[2753]: info: VolumeController:: Volume=undefined Mute =false Aug 31 12:18:04 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:04 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:04 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:16 volumio volumio[2753]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::volumioGetState Aug 31 12:18:16 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:16 volumio volumio[2753]: info: Restoring Previous Volume level: 100 false false Aug 31 12:18:16 volumio volumio[2753]: info: Output device has changed, restarting MPD Aug 31 12:18:16 volumio sudo[6294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 12:18:16 volumio sudo[6294]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:16 volumio volumio[2753]: info: Output device has changed, restarting Shairport Sync Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:16 volumio sudo[6294]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:16 volumio sudo[6299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 12:18:16 volumio sudo[6299]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:16 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 12:18:16 volumio volumio[2753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 12:18:16 volumio volumio[2753]: info: Volume configurations have been set Aug 31 12:18:16 volumio systemd[1]: mpd.service: Succeeded. Aug 31 12:18:16 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 31 12:18:16 volumio volumio[2753]: info: Updating Volume Controller Parameters: Device: 2 Name: JustBoom Digi Boards Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 31 12:18:16 volumio systemd[1]: Starting Music Player Daemon... Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 31 12:18:16 volumio volumio[2753]: info: Disabling external Volume Control Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 12:18:16 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:16 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:16 volumio volumio[2753]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 31 12:18:16 volumio volumio[2753]: info: MPD Permissions set Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio sudo[6317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 12:18:16 volumio sudo[6317]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:16 volumio sudo[6317]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 12:18:16 volumio volumio[2753]: info: Getting Alsa Cards List without I2S DAC Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 12:18:16 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:17 volumio volumio[2753]: Invalid card number. Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 12:18:17 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 12:18:17 volumio volumio[2753]: info: Starting Shairport Sync Aug 31 12:18:17 volumio sudo[6335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 12:18:17 volumio sudo[6335]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 12:18:17 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 31 12:18:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 12:18:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 12:18:17 volumio sudo[6335]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:17 volumio volumio[2753]: info: Shairport-Sync Started Aug 31 12:18:17 volumio mpd[6325]: Aug 31 12:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 12:18:17 volumio systemd[1]: Started Music Player Daemon. Aug 31 12:18:17 volumio sudo[6299]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:17 volumio volumio[2753]: error: updateQueue error: null Aug 31 12:18:20 volumio volumio[2753]: info: CoreCommandRouter::volumioPlay Aug 31 12:18:20 volumio volumio[2753]: info: CoreStateMachine::play index undefined Aug 31 12:18:20 volumio volumio[2753]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 12:18:20 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:20 volumio volumio[2753]: info: CoreStateMachine::startPlaybackTimer Aug 31 12:18:20 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:20 volumio volumio[2753]: verbose: ControllerMpd::sendMpdCommand play Aug 31 12:18:20 volumio volumio[2753]: info: sendMpdCommand play took 1 milliseconds Aug 31 12:18:20 volumio volumio[2753]: info: CoreCommandRouter::servicePushState Aug 31 12:18:20 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:20 volumio volumio[2753]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002KF4.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Black Water","title":"Black Water","artist":"The Doobie Brothers","album":"What Were Once Vices Are Now Habits","streaming":true,"disableUiControls":true,"duration":72,"seek":47527,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 31 12:18:20 volumio volumio[2753]: verbose: CURRENT POSITION 0 Aug 31 12:18:20 volumio volumio[2753]: info: CoreStateMachine::syncState stateService play Aug 31 12:18:20 volumio volumio[2753]: info: CoreStateMachine::syncState currentStatus pause Aug 31 12:18:20 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:20 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:20 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:20 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:20 volumio volumio[2753]: info: [1756635500714] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Aug 31 12:18:21 volumio volumio[2753]: info: VolumeController::SetAlsaVolume100 Aug 31 12:18:21 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:21 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:21 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:21 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:21 volumio volumio[2753]: info: [1756635501276] [RadioParadise] received new metadata: {"time":118,"artist":"Fats Domino","title":"Walking to New Orleans","album":"The Fats Domino Collection - The Golden Greats","year":"1960","cover":"https://img.radioparadise.com/covers/l/B0000032FC.jpg","cover_med":"https://img.radioparadise.com/covers/m/B0000032FC.jpg","cover_small":"https://img.radioparadise.com/covers/s/B0000032FC.jpg"} Aug 31 12:18:21 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:21 volumio volumio[2753]: info: CoreCommandRouter::servicePushState Aug 31 12:18:21 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:21 volumio volumio[2753]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0000032FC.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Walking to New Orleans","title":"Walking to New Orleans","artist":"Fats Domino","album":"The Fats Domino Collection - The Golden Greats","streaming":true,"disableUiControls":true,"duration":123,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 31 12:18:21 volumio volumio[2753]: verbose: CURRENT POSITION 0 Aug 31 12:18:21 volumio volumio[2753]: info: CoreStateMachine::syncState stateService play Aug 31 12:18:21 volumio volumio[2753]: info: CoreStateMachine::syncState currentStatus play Aug 31 12:18:21 volumio volumio[2753]: info: Received an update from plugin. extracting info from payload Aug 31 12:18:21 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:21 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:21 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:21 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:21 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:21 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:21 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:21 volumio volumio[2753]: info: [1756635501291] [RadioParadise] setting new timer with duration of 123000 seconds. Aug 31 12:18:49 volumio volumio[2753]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 12:18:49 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 12:18:49 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:49 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 12:18:49 volumio volumio[2753]: info: Getting Alsa Cards List without I2S DAC Aug 31 12:18:49 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 12:18:49 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:50 volumio volumio[2753]: Invalid card number. Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 12:18:50 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 12:18:57 volumio volumio[2753]: info: CoreCommandRouter::volumioSeek Aug 31 12:18:57 volumio volumio[2753]: info: CoreStateMachine::seek Aug 31 12:18:57 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:57 volumio volumio[2753]: info: TRACKBLOCK {"service":"radio_paradise","type":"track","trackType":"Rparadise Mellow","radioType":"rp","albumart":"https://img.radioparadise.com/covers/l/B0000032FC.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Walking to New Orleans","artist":"Fats Domino","album":"The Fats Domino Collection - The Golden Greats","duration":123,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 31 12:18:57 volumio volumio[2753]: info: CoreStateMachine::startPlaybackTimer Aug 31 12:18:57 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:57 volumio volumio[2753]: info: [1756635537693] [RadioParadise] seek to 50000 Aug 31 12:18:57 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:57 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:57 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:57 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:58 volumio volumio[2753]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 31 12:18:58 volumio volumio[2753]: info: Preparing to save Alsa Options, stopping services first Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::volumioGetState Aug 31 12:18:58 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::volumioPause Aug 31 12:18:58 volumio volumio[2753]: info: CoreStateMachine::pause Aug 31 12:18:58 volumio volumio[2753]: info: CoreStateMachine::stPlaybackTimer Aug 31 12:18:58 volumio volumio[2753]: info: CoreStateMachine::servicePause Aug 31 12:18:58 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::servicePause Aug 31 12:18:58 volumio volumio[2753]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 12:18:58 volumio volumio[2753]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Headphones"},"i2s":true,"i2sid":{"value":"allo-digione","label":"Allo DigiOne"}} Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:58 volumio volumio[2753]: info: Enabling I2S DAC: Allo DigiOne Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 31 12:18:58 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:58 volumio sudo[6415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 31 12:18:58 volumio sudo[6415]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio sudo[6415]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:59 volumio sudo[6418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r justboom-digi Aug 31 12:18:59 volumio sudo[6418]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio sudo[6418]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:59 volumio volumio[2753]: info: Removed overlay justboom-digi Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:59 volumio sudo[6430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-digione Aug 31 12:18:59 volumio sudo[6430]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Aug 31 12:18:59 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Aug 31 12:18:59 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Aug 31 12:18:59 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Aug 31 12:18:59 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Aug 31 12:18:59 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock44-gpio Aug 31 12:18:59 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock48-gpio Aug 31 12:18:59 volumio kernel: wm8804 1-003b: revision E Aug 31 12:18:59 volumio sudo[6430]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:59 volumio volumio[2753]: warn: Unable to locate the device Headphones a reboot may be required. Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: Invalid card number. Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 31 12:18:59 volumio volumio[2753]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo DigiOne Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 31 12:18:59 volumio volumio[2753]: info: Disabling external Volume Control Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 31 12:18:59 volumio volumio[2753]: info: Preparing to generate the ALSA configuration file Aug 31 12:18:59 volumio volumio[2753]: info: sendMpdCommand pause took 307 milliseconds Aug 31 12:18:59 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::servicePushState Aug 31 12:18:59 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:59 volumio volumio[2753]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0000032FC.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Walking to New Orleans","title":"Walking to New Orleans","artist":"Fats Domino","album":"The Fats Domino Collection - The Golden Greats","streaming":true,"disableUiControls":true,"duration":123,"seek":51901,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 31 12:18:59 volumio volumio[2753]: verbose: CURRENT POSITION 0 Aug 31 12:18:59 volumio volumio[2753]: info: CoreStateMachine::syncState stateService pause Aug 31 12:18:59 volumio volumio[2753]: info: CoreStateMachine::syncState currentStatus pause Aug 31 12:18:59 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:59 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:59 volumio volumio[2753]: info: CoreStateMachine::stPlaybackTimer Aug 31 12:18:59 volumio volumio[2753]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 31 12:18:59 volumio volumio[2753]: info: I2S Param [object Object] successfully enabled Aug 31 12:18:59 volumio volumio[2753]: info: VolumeController:: Volume=undefined Mute =false Aug 31 12:18:59 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:59 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 12:18:59 volumio volumio[2753]: info: Getting Alsa Cards List without I2S DAC Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: Invalid card number. Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 12:18:59 volumio volumio[2753]: info: touch_display: Setting screensaver timeout to 60 seconds. Aug 31 12:18:59 volumio volumio[2753]: info: Asound.conf file written Aug 31 12:18:59 volumio sudo[6475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 31 12:18:59 volumio sudo[6475]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio sudo[6475]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:59 volumio volumio[2753]: No state is present for card sndrpihifiberry Aug 31 12:18:59 volumio volumio[2753]: Found hardware: "RPi-simple" "" "" "" "" Aug 31 12:18:59 volumio volumio[2753]: Hardware is initialized using a generic method Aug 31 12:18:59 volumio volumio[2753]: No state is present for card sndrpihifiberry Aug 31 12:18:59 volumio volumio[2753]: info: Output device has changed, restarting MPD Aug 31 12:18:59 volumio sudo[6480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 12:18:59 volumio sudo[6480]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio sudo[6480]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:59 volumio volumio[2753]: info: Output device has changed, restarting Shairport Sync Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:59 volumio sudo[6483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 12:18:59 volumio sudo[6483]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 12:18:59 volumio volumio[2753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 12:18:59 volumio volumio[2753]: info: Output device has changed, restarting MPD Aug 31 12:18:59 volumio systemd[1]: mpd.service: Succeeded. Aug 31 12:18:59 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 12:18:59 volumio volumio[2753]: info: Output device has changed, restarting Shairport Sync Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:18:59 volumio sudo[6490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 12:18:59 volumio sudo[6490]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio sudo[6490]: pam_unix(sudo:session): session closed for user root Aug 31 12:18:59 volumio systemd[1]: Starting Music Player Daemon... Aug 31 12:18:59 volumio sudo[6492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 12:18:59 volumio sudo[6492]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 31 12:18:59 volumio systemd[1]: mpd.service: Succeeded. Aug 31 12:18:59 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 12:18:59 volumio systemd[1]: Starting Music Player Daemon... Aug 31 12:18:59 volumio volumio[2753]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 12:18:59 volumio volumio[2753]: info: VolumeController::SetAlsaVolume0 Aug 31 12:18:59 volumio volumio[2753]: info: CoreStateMachine::pushState Aug 31 12:18:59 volumio volumio[2753]: info: CorePlayQueue::getTrack 0 Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::volumioPushState Aug 31 12:18:59 volumio volumio[2753]: info: MPD Permissions set Aug 31 12:18:59 volumio volumio[2753]: info: MPD Permissions set Aug 31 12:18:59 volumio volumio[2753]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:18:59 volumio sudo[6498]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 12:18:59 volumio sudo[6498]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:18:59 volumio sudo[6498]: pam_unix(sudo:session): session closed for user root Aug 31 12:19:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:19:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:19:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:19:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:19:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:19:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 12:19:00 volumio volumio[2753]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 12:19:00 volumio volumio[2753]: info: Starting Shairport Sync Aug 31 12:19:00 volumio volumio[2753]: info: Starting Shairport Sync Aug 31 12:19:00 volumio sudo[6511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 12:19:00 volumio sudo[6511]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:19:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 12:19:00 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 31 12:19:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 12:19:00 volumio sudo[6514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 12:19:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 12:19:00 volumio sudo[6514]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 12:19:00 volumio sudo[6511]: pam_unix(sudo:session): session closed for user root Aug 31 12:19:00 volumio volumio[2753]: info: Shairport-Sync Started Aug 31 12:19:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 12:19:00 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 31 12:19:00 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 31 12:19:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 12:19:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 12:19:00 volumio sudo[6514]: pam_unix(sudo:session): session closed for user root Aug 31 12:19:00 volumio volumio[2753]: info: Shairport-Sync Started Aug 31 12:19:00 volumio mpd[6502]: Aug 31 12:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 12:19:00 volumio systemd[1]: Started Music Player Daemon. Aug 31 12:19:00 volumio sudo[6492]: pam_unix(sudo:session): session closed for user root Aug 31 12:19:00 volumio sudo[6483]: pam_unix(sudo:session): session closed for user root Aug 31 12:19:00 volumio volumio[2753]: error: MPD error: The expression evaluated to a falsy value: Aug 31 12:19:00 volumio volumio[2753]: assert.ok(self.idling) Aug 31 12:19:00 volumio volumio[2753]: error: The expression evaluated to a falsy value: Aug 31 12:19:00 volumio volumio[2753]: assert.ok(self.idling) Aug 31 12:19:00 volumio volumio[2753]: error: updateQueue error: null Aug 31 12:19:00 volumio volumio[2753]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Aug 31 12:19:00 volumio volumio[2753]: info: PLUGIN onReboot : networkfs Aug 31 12:19:00 volumio volumio[2753]: info: PLUGIN onReboot : audiophonicsonoff Aug 31 12:19:00 volumio volumio[2753]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 12:19:00 volumio volumio[2753]: TypeError: Cannot read property 'writeSync' of undefined Aug 31 12:19:00 volumio volumio[2753]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Aug 31 12:19:00 volumio volumio[2753]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Aug 31 12:19:00 volumio volumio[2753]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Aug 31 12:19:00 volumio volumio[2753]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 31 12:19:00 volumio volumio[2753]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 31 12:19:00 volumio volumio[2753]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Aug 31 12:19:00 volumio volumio[2753]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Aug 31 12:19:00 volumio volumio[2753]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Aug 31 12:19:00 volumio volumio[2753]: at Socket.emit (events.js:315:20) Aug 31 12:19:00 volumio volumio[2753]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 31 12:19:00 volumio volumio[2753]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 31 12:19:00 volumio volumio[2753]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 12:19:01 volumio sudo[6531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 12:18 Aug 31 12:19:01 volumio sudo[6531]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"