-- Logs begin at Thu 2019-02-14 06:11:59 AST, end at Tue 2025-09-09 13:46:00 AST. -- Sep 09 13:45:10 volumio wpa_supplicant[873]: wlan0: Failed to initiate sched scan Sep 09 13:45:22 volumio wpa_supplicant[873]: wlan0: Failed to initiate sched scan Sep 09 13:45:28 volumio volumio[1041]: info: CoreCommandRouter::volumioGetState Sep 09 13:45:28 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:30 volumio volumio[1041]: info: Executing endpoint metavolumio Sep 09 13:45:30 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 13:45:31 volumio volumio[1041]: info: Retrieving Cloud Streaming UI Sep 09 13:45:31 volumio volumio[1041]: info: Getting Tidal Cloud Configuration Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 13:45:31 volumio volumio[1041]: info: Getting Qobuz Cloud Configuration Sep 09 13:45:31 volumio volumio[1041]: info: Asking plugin for UI Config Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 13:45:31 volumio volumio[1041]: info: Getting Spotify Cloud Configuration Sep 09 13:45:31 volumio volumio[1041]: info: Asking plugin for UI Config Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 13:45:31 volumio volumio[1041]: info: Saving Spotify Acccount Sep 09 13:45:31 volumio volumio[1041]: info: Got it Sep 09 13:45:31 volumio volumio[1041]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 09 13:45:31 volumio volumio[1041]: info: Got Tidal Cloud Configuration Sep 09 13:45:31 volumio volumio[1041]: info: Got it Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::volumioGetBrowseSources Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::volumioGetBrowseSources Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::volumioGetBrowseSources Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 09 13:45:31 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 09 13:45:33 volumio wpa_supplicant[873]: wlan0: Failed to initiate sched scan Sep 09 13:45:35 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 09 13:45:39 volumio volumio[1041]: info: CoreCommandRouter::volumioGetState Sep 09 13:45:39 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:41 volumio volumio[1041]: info: Executing endpoint metavolumio Sep 09 13:45:41 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 09 13:45:41 volumio volumio[1041]: error: Failed request for metavolumio API Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 09 13:45:42 volumio volumio[1041]: Invalid card number. Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 09 13:45:42 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 09 13:45:44 volumio wpa_supplicant[873]: wlan0: Failed to initiate sched scan Sep 09 13:45:55 volumio wpa_supplicant[873]: wlan0: Failed to initiate sched scan Sep 09 13:45:57 volumio volumio[1041]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 09 13:45:57 volumio volumio[1041]: info: Preparing to save Alsa Options, stopping services first Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::volumioGetState Sep 09 13:45:57 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::volumioPause Sep 09 13:45:57 volumio volumio[1041]: info: CoreStateMachine::pause Sep 09 13:45:57 volumio volumio[1041]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"M15i Mobile Headphone Amplifier"},"i2s":true,"i2sid":{"value":"audiophonics-es9028q2m-dac","label":"Audiophonics I-Sabre ES9028Q2M"}} Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:57 volumio volumio[1041]: info: Enabling I2S DAC: Audiophonics I-Sabre ES9028Q2M Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:57 volumio sudo[2453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Sep 09 13:45:57 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:57 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:57 volumio volumio[1041]: info: No Overlays Loaded Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:57 volumio sudo[2456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay i-sabre-q2m Sep 09 13:45:57 volumio sudo[2456]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:57 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Sep 09 13:45:57 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Sep 09 13:45:57 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Sep 09 13:45:57 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Sep 09 13:45:57 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Sep 09 13:45:57 volumio sudo[2456]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:57 volumio volumio[1041]: warn: Unable to locate the device M15i Mobile Headphone Amplifier a reboot may be required. Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:57 volumio volumio[1041]: info: Found match in i2s Card Database: setting mixer Digital for card Audiophonics I-Sabre ES9028Q2M Sep 09 13:45:57 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: [0x00000001] -121 Sep 09 13:45:57 volumio kernel: snd-rpi-i-sabre-q2m soc:sound: Audiophonics Device ID : FFFFFF87 Sep 09 13:45:57 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: [0x00000002] -121 Sep 09 13:45:57 volumio kernel: snd-rpi-i-sabre-q2m soc:sound: Audiophonics API revision : FFFFFF87 Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 09 13:45:57 volumio volumio[1041]: info: Updating Volume Controller Parameters: Device: 2 Name: Audiophonics I-Sabre ES9028Q2M Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 09 13:45:57 volumio volumio[1041]: info: Disabling external Volume Control Sep 09 13:45:57 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] -5 Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 09 13:45:57 volumio volumio[1041]: info: Preparing to generate the ALSA configuration file Sep 09 13:45:57 volumio volumio[1041]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 09 13:45:57 volumio volumio[1041]: info: Reading ALSA contributions from plugins. Sep 09 13:45:57 volumio volumio[1041]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 09 13:45:57 volumio volumio[1041]: info: I2S Param [object Object] successfully enabled Sep 09 13:45:57 volumio volumio[1041]: info: VolumeController:: Volume=undefined Mute =false Sep 09 13:45:57 volumio volumio[1041]: info: CoreStateMachine::pushState Sep 09 13:45:57 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::volumioPushState Sep 09 13:45:57 volumio volumio[1041]: info: MRS: Pushing multiroomSync output update for this device Sep 09 13:45:57 volumio volumio[1041]: info: MRS: Pushing multiroomSync output Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::volumioGetState Sep 09 13:45:57 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 09 13:45:57 volumio volumio[1041]: info: Getting Alsa Cards List without I2S DAC Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:57 volumio volumio[1041]: Invalid card number. Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 09 13:45:57 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 09 13:45:57 volumio volumio[1041]: info: Asound.conf file written Sep 09 13:45:57 volumio sudo[2505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 09 13:45:58 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio volumio[1041]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Sep 09 13:45:58 volumio volumio[1041]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Sep 09 13:45:58 volumio volumio[1041]: info: Output device has changed, restarting MPD Sep 09 13:45:58 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 Sep 09 13:45:58 volumio sudo[2510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 13:45:58 volumio sudo[2510]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2510]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio volumio[1041]: info: Output device has changed, restarting Shairport Sync Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:58 volumio sudo[2513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 13:45:58 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio systemd[1]: Stopping Music Player Daemon... Sep 09 13:45:58 volumio volumio[1041]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 13:45:58 volumio volumio[1041]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio systemd[1]: mpd.service: Succeeded. Sep 09 13:45:58 volumio systemd[1]: Stopped Music Player Daemon. Sep 09 13:45:58 volumio volumio[1041]: info: QobuzConnect: setDeactiveState invoked Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::volumioGetState Sep 09 13:45:58 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:58 volumio vtcs[1898]: [2025-09-09 13:45:58.167] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 09 13:45:58 volumio vtcs[1898]: [2025-09-09 13:45:58.168] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Sep 09 13:45:58 volumio vtcs[1898]: [2025-09-09 13:45:58.168] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 09 13:45:58 volumio systemd[1]: Starting Music Player Daemon... Sep 09 13:45:58 volumio volumio[1041]: info: Output device has changed, restarting MPD Sep 09 13:45:58 volumio sudo[2522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 09 13:45:58 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Sep 09 13:45:58 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 09 13:45:58 volumio systemd[1]: vtcs.service: Succeeded. Sep 09 13:45:58 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Sep 09 13:45:58 volumio sudo[2525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 13:45:58 volumio sudo[2525]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2525]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio volumio[1041]: info: Output device has changed, restarting Shairport Sync Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:58 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio sudo[2519]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 09 13:45:58 volumio sudo[2519]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 13:45:58 volumio sudo[2528]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2519]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 09 13:45:58 volumio systemd[1]: mpd.service: Succeeded. Sep 09 13:45:58 volumio systemd[1]: Stopped Music Player Daemon. Sep 09 13:45:58 volumio systemd[1]: Starting Music Player Daemon... Sep 09 13:45:58 volumio volumio[1041]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 13:45:58 volumio volumio[1041]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: QobuzConnect: setDeactiveState invoked Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::volumioGetState Sep 09 13:45:58 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:58 volumio sudo[2540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 09 13:45:58 volumio sudo[2540]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2536]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 09 13:45:58 volumio sudo[2536]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 09 13:45:58 volumio sudo[2543]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2536]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio sudo[2540]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio sudo[2549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 09 13:45:58 volumio sudo[2549]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2543]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio sudo[2555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 09 13:45:58 volumio sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio sudo[2549]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio sudo[2555]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio sudo[2562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 09 13:45:58 volumio sudo[2562]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 09 13:45:58 volumio sudo[2564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 09 13:45:58 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio qobuz-connect[1849]: 20250909 13:45:58.542 [1849.1849] INFO SampleApp: Stopping Local configuration server Sep 09 13:45:58 volumio qobuz-connect[1849]: 20250909 13:45:58.552 [1849.1849] INFO SampleApp: shat down connection on UNIX socket Sep 09 13:45:58 volumio systemd[1]: qobuz-connect.service: Succeeded. Sep 09 13:45:58 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio systemd[1]: Started Volumio Qobuz Connect Service. Sep 09 13:45:58 volumio sudo[2562]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio volumio[1041]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 09 13:45:58 volumio volumio[1041]: info: MPD Permissions set Sep 09 13:45:58 volumio volumio[1041]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 09 13:45:58 volumio volumio[1041]: info: MPD Permissions set Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 13:45:58 volumio volumio[1041]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 09 13:45:58 volumio volumio[1041]: info: QobuzConnect: setDeactiveState invoked Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::volumioGetState Sep 09 13:45:58 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio sudo[2568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio sudo[2568]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 09 13:45:58 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Sep 09 13:45:58 volumio systemd[1]: qobuz-connect.service: Succeeded. Sep 09 13:45:58 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 09 13:45:58 volumio systemd[1]: Started Volumio Qobuz Connect Service. Sep 09 13:45:58 volumio sudo[2568]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 13:45:58 volumio volumio[1041]: info: Executing endpoint qc_getconfig Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 13:45:58 volumio volumio[1041]: info: Executing endpoint qc_getconfig Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 09 13:45:58 volumio volumio[1041]: info: Starting Shairport Sync Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.777 [2575.2575] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.782 [2575.2575] INFO VolumeManager: [0x14d5338]: Setting new playback volume: 75 Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.782 [2575.2575] INFO VolumeManager: [0x14d5338]: Setting new mute state: 0 Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.782 [2575.2575] INFO QobuzConnect: [0x14d5d08]: Client initialized! Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.782 [2575.2575] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.796 [2575.2575] INFO LocalConfigManager: [0x14d4c18]: Starting Local Configuration server Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.796 [2575.2575] INFO SampleApp: Starting Local configuration server Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.796 [2575.2575] INFO SampleApp: Connected to UNIX socket client 0x14caf60 Sep 09 13:45:58 volumio volumio[1041]: info: Starting Shairport Sync Sep 09 13:45:58 volumio sudo[2583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 13:45:58 volumio sudo[2583]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio volumio[1041]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 09 13:45:58 volumio volumio[1041]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 09 13:45:58 volumio sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 13:45:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 09 13:45:58 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 09 13:45:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 13:45:58 volumio sudo[2583]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 09 13:45:58 volumio qobuz-connect[2575]: 20250909 13:45:58.927 [2575.2575] INFO SampleApp: Playback volume changed: 75 Sep 09 13:45:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 09 13:45:58 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 09 13:45:58 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 09 13:45:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 09 13:45:58 volumio volumio[1041]: info: CoreCommandRouter::volumioGetState Sep 09 13:45:58 volumio volumio[1041]: info: CorePlayQueue::getTrack 0 Sep 09 13:45:58 volumio volumio[1041]: info: Shairport-Sync Started Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 09 13:45:58 volumio volumio[1041]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 09 13:45:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 09 13:45:58 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:58 volumio volumio[1041]: info: Shairport-Sync Started Sep 09 13:45:59 volumio mpd[2547]: Sep 09 13:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 09 13:45:59 volumio systemd[1]: Started Music Player Daemon. Sep 09 13:45:59 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:59 volumio sudo[2528]: pam_unix(sudo:session): session closed for user root Sep 09 13:45:59 volumio volumio[1041]: error: MPD error: The expression evaluated to a falsy value: Sep 09 13:45:59 volumio volumio[1041]: assert.ok(self.idling) Sep 09 13:45:59 volumio volumio[1041]: error: The expression evaluated to a falsy value: Sep 09 13:45:59 volumio volumio[1041]: assert.ok(self.idling) Sep 09 13:45:59 volumio volumio[1041]: error: updateQueue error: null Sep 09 13:45:59 volumio volumio[1041]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Sep 09 13:45:59 volumio volumio[1041]: info: PLUGIN onReboot : networkfs Sep 09 13:45:59 volumio volumio[1041]: info: PLUGIN onReboot : audiophonicsonoff Sep 09 13:45:59 volumio volumio[1041]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 13:46:00 volumio volumio[1041]: TypeError: Cannot read property 'writeSync' of undefined Sep 09 13:46:00 volumio volumio[1041]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Sep 09 13:46:00 volumio volumio[1041]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Sep 09 13:46:00 volumio volumio[1041]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Sep 09 13:46:00 volumio volumio[1041]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 09 13:46:00 volumio volumio[1041]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 09 13:46:00 volumio volumio[1041]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Sep 09 13:46:00 volumio volumio[1041]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Sep 09 13:46:00 volumio volumio[1041]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Sep 09 13:46:00 volumio volumio[1041]: at Socket.emit (events.js:315:20) Sep 09 13:46:00 volumio volumio[1041]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 09 13:46:00 volumio volumio[1041]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 09 13:46:00 volumio volumio[1041]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 13:46:00 volumio sudo[2603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-09 13:45 Sep 09 13:46:00 volumio sudo[2603]: 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"