-- Logs begin at Tue 2025-12-09 09:12:15 IST, end at Wed 2026-01-28 08:24:07 IST. -- Jan 28 08:23:53 ifi-zen-stream systemd[1]: Starting Daily apt download activities... Jan 28 08:23:53 ifi-zen-stream volumio[1164]: Forking 3 albumart workers Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::initPlayerControls Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: Express server listening on port 3000 Jan 28 08:23:54 ifi-zen-stream volumio[1164]: [Metrics] WebUI: 8s 300.86ms Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreStateMachine::resetVolumioState Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreStateMachine::getcurrentVolume Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 08:23:54 ifi-zen-stream volumio[1164]: Meta server listening Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: Server listening on port 6599 Jan 28 08:23:54 ifi-zen-stream sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jan 28 08:23:54 ifi-zen-stream volumio[1164]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: Completed loading Core Plugins Jan 28 08:23:54 ifi-zen-stream sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: Preparing to generate the ALSA configuration file Jan 28 08:23:54 ifi-zen-stream sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jan 28 08:23:54 ifi-zen-stream sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: ConvSwitch: Connected to server Jan 28 08:23:54 ifi-zen-stream node[475]: ConvSwitch: Add client volumio Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: MetaClient: Connected to server Jan 28 08:23:54 ifi-zen-stream node[477]: metadata_handler Jan 28 08:23:54 ifi-zen-stream systemd[1]: Started iFi Streamer Tidal Connect Service. Jan 28 08:23:54 ifi-zen-stream sudo[1399]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:54 ifi-zen-stream systemd[1]: Started iFi Streamer Tidal Metadata Service. Jan 28 08:23:54 ifi-zen-stream volumio[1164]: info: Volumio Network Manager: Network status updated: 3 Jan 28 08:23:55 ifi-zen-stream node[477]: A new connection has been established. Jan 28 08:23:55 ifi-zen-stream node[477]: metadata_handler Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: NAA Service Started Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 28 08:23:55 ifi-zen-stream systemd[1]: Started iFi Streamer Qobuz Connect Service. Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 28 08:23:55 ifi-zen-stream sudo[1402]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreStateMachine::getcurrentVolume Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: ConvSwitch: Get message: success Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: MetaClient: Get message: success Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Qobuz Connect Service Started Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Tidal Connect Service Started Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Reloading queue from file Jan 28 08:23:55 ifi-zen-stream sudo[1269]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: VolumeController::SetAlsaVolume100 Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Asound.conf file unchanged, so no further update is needed Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Tidal connect: Output device has changed Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Output device has changed, restarting MPD Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Qobuz connect: Output device has changed Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Roon Ready: Output device has changed Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Spotify: Output device has changed Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: ___________ START PLUGINS ___________ Jan 28 08:23:55 ifi-zen-stream sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 08:23:55 ifi-zen-stream sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:55 ifi-zen-stream sudo[1451]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: ControllerMpd::onStart: Initializing MPD Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Creating MPD Configuration file Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Starting sample application Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Device Name: ZEN Stream Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Manufacturer: Sample manufacturer Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Model: iFi Streamer Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Serial Number: ifi:9a:43:3d:a8:4d:ac Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Audio output device: volumio Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Volume control device: default Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Volume control element: Master Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.650 [1461.1461] DEBUG SampleApp: Local config server port: 8000 Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.656 [1461.1461] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.663 [1461.1461] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Jan 28 08:23:55 ifi-zen-stream play[1418]: [Info] MetaClient: MetaClient SDK -- v1.02 Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: [1769568835671] CoreMusicLibrary::Adding element Media Servers Jan 28 08:23:55 ifi-zen-stream node[477]: A new connection has been established. Jan 28 08:23:55 ifi-zen-stream node[477]: metadata_handler Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 08:23:55 ifi-zen-stream sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jan 28 08:23:55 ifi-zen-stream sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 08:23:55 ifi-zen-stream sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:55 ifi-zen-stream sudo[1466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:55 ifi-zen-stream play[1418]: [Info] MetaClient: MetaClientInit Jan 28 08:23:55 ifi-zen-stream play[1418]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jan 28 08:23:55 ifi-zen-stream node[475]: ConvSwitch: Add client qobuz-connect Jan 28 08:23:55 ifi-zen-stream sudo[1466]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:55 ifi-zen-stream play[1418]: [Info] ConvSwitch: ConvSwitchInit Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: [1769568835792] CoreMusicLibrary::Adding element Last_100 Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.797 [1461.1461] DEBUG ActiveStateManager: [0xab03eff8]: Creating new Active State Manager Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.798 [1461.1461] DEBUG NetworkActivityTracker: [0xab03f068]: Creating new Network Activity Tracker Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.804 [1461.1461] DEBUG EndpointManager: [0xab03ffe0]: Creating Endpoint Manager Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.804 [1461.1461] DEBUG PlaybackSessionManager: [0xab0403d0]: Creating Playback Session Manager Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.804 [1461.1461] DEBUG BackoffTimer: [0xab040548]: Creating Backoff Timer Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG MediaEngine: [0xab040668]: Creating new Media Engine Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG AudioRenderer: [0xab040888]: Creating Audio Renderer Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] TRACE MediaEngineCommandSequencer: [0xab0409e0]: Creating Media Engine Command Sequencer Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG PlaybackControlsManager: [0xab040a40]: Creating new Playback Controls Manager Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG VolumeManager: [0xab040ad0]: Creating new Volume Manager Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG ProtocolHandler: [0xab040da8]: Creating Protocol Handler Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG MessageReceiver: [0xab040e48]: Creating Message Receiver Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG RendererActivationController: [0xab040fc0]: Creating new Renderer Activation Controller Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] TRACE RendererActivationCommandSequencer: [0xab041068]: Creating Renderer Activation Command Sequencer Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG ReportingManager: [0xab041178]: Creating new Reporting Manager Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG StreamingEventReporter: [0xab0411c0]: Creating Streaming Event Reporter Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG BackoffTimer: [0xab0412a0]: Creating Backoff Timer Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG TokenExpiryController: [0xab0413b0]: Creating Token Expiry Controller Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG UserInactivityManager: [0xab041400]: Creating new User Inactivity Manager Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] INFO VolumeManager: [0xab040ad0]: Setting new playback volume: 100 Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] INFO VolumeManager: [0xab040ad0]: Setting new mute state: 1 Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] DEBUG SampleApp: Qobuz Connect has been initialized Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] TRACE SampleApp: Running event loop Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] INFO QobuzConnect: [0xab0414a0]: Client initialized! Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.805 [1461.1461] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream, service name: _qobuz-connect._tcp Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Starting RoonReady Sync Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.850 [1461.1461] DEBUG SampleApp: Avahi client state changed: RUNNING Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.863 [1461.1461] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.906 [1461.1461] INFO LocalConfigManager: [0xab0403b0]: Starting Local Configuration server Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.906 [1461.1461] INFO SampleApp: Starting Local configuration server Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.908 [1461.1461] INFO SampleApp: Playback mute state changed: 1 Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.908 [1461.1461] ERROR SampleApp: Unable to set mute state, no ALSA volume element Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.908 [1461.1461] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.908 [1461.1461] INFO SampleApp: Playback volume changed: 100 Jan 28 08:23:55 ifi-zen-stream play[1418]: 20260128 08:23:55.908 [1461.1461] ERROR SampleApp: Unable to set volume, no ALSA volume element Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: [1769568835909] CoreMusicLibrary::Adding element Webradio Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 08:23:55 ifi-zen-stream sudo[1467]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 08:23:55 ifi-zen-stream sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jan 28 08:23:55 ifi-zen-stream sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:55 ifi-zen-stream volumio[1164]: info: Initializing BBC Radios Jan 28 08:23:56 ifi-zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jan 28 08:23:56 ifi-zen-stream sudo[1477]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:56 ifi-zen-stream volumio[1164]: Starting albumart workers Jan 28 08:23:56 ifi-zen-stream volumio[1164]: Starting albumart workers Jan 28 08:23:56 ifi-zen-stream play[1486]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jan 28 08:23:56 ifi-zen-stream systemd[1]: apt-daily.service: Succeeded. Jan 28 08:23:56 ifi-zen-stream systemd[1]: Started Daily apt download activities. Jan 28 08:23:56 ifi-zen-stream systemd[1]: Starting Daily apt upgrade and clean activities... Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 08:23:56 ifi-zen-stream volumio[1164]: Starting albumart workers Jan 28 08:23:56 ifi-zen-stream sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -snf /usr/local/bin/airplay2 /usr/local/bin/shairport-sync Jan 28 08:23:56 ifi-zen-stream sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 08:23:56 ifi-zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jan 28 08:23:56 ifi-zen-stream sudo[1504]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 08:23:56 ifi-zen-stream volumio[1164]: info: Volumio Calling Home Jan 28 08:23:56 ifi-zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jan 28 08:23:56 ifi-zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jan 28 08:23:56 ifi-zen-stream play[1418]: 20260128 08:23:56.883 [1461.1461] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Jan 28 08:23:56 ifi-zen-stream node[475]: ConvSwitch: Add client roon-ready Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setRepeat null single undefined Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setRandom null Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: Starting RoonReady Sync Jan 28 08:23:57 ifi-zen-stream sudo[1625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Jan 28 08:23:57 ifi-zen-stream sudo[1625]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: Discovery: A device disappeared from network Jan 28 08:23:57 ifi-zen-stream sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jan 28 08:23:57 ifi-zen-stream sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:57 ifi-zen-stream sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jan 28 08:23:57 ifi-zen-stream systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Jan 28 08:23:57 ifi-zen-stream sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:57 ifi-zen-stream node[475]: ConvSwitch: Drop client qobuz-connect Jan 28 08:23:57 ifi-zen-stream node[477]: Closing connection with the client qobuz-connect Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: ConvSwitch: Get message: request:pause Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:23:57 ifi-zen-stream systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:57 ifi-zen-stream systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Jan 28 08:23:57 ifi-zen-stream systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:57 ifi-zen-stream sudo[1625]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: Airplay2 Started Jan 28 08:23:57 ifi-zen-stream volumio[1164]: error: Could not retrieve plugin music_service airplay1_switch Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: Disabling plugin airplay1_switch Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: Done. Jan 28 08:23:57 ifi-zen-stream volumio[1164]: info: RoonReady Started Jan 28 08:23:57 ifi-zen-stream node[475]: ConvSwitch: Drop client roon-ready Jan 28 08:23:57 ifi-zen-stream systemd[1]: Stopping iFi Streamer Roon Ready Service... Jan 28 08:23:57 ifi-zen-stream systemd[1]: Started iFi Streamer Spotify Connect Service. Jan 28 08:23:57 ifi-zen-stream sudo[1638]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: MPD Permissions set Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: MPD Permissions set Jan 28 08:23:58 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Jan 28 08:23:58 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Jan 28 08:23:58 ifi-zen-stream sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Jan 28 08:23:58 ifi-zen-stream systemd[1]: Stopped iFi Streamer Roon Ready Service. Jan 28 08:23:58 ifi-zen-stream sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:58 ifi-zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jan 28 08:23:58 ifi-zen-stream sudo[1630]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Discovery: adding 38a04a19-e05c-47ad-8f76-2979ca254a12 Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Discovery: Found device ZEN Stream Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioGetState Jan 28 08:23:58 ifi-zen-stream sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jan 28 08:23:58 ifi-zen-stream sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:58 ifi-zen-stream volumio[1164]: error: Could not retrieve plugin audio_interface multiroom Jan 28 08:23:58 ifi-zen-stream sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jan 28 08:23:58 ifi-zen-stream sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:58 ifi-zen-stream ifi_metadata[1410]: [Info] MetaClient: MetaClient SDK -- v1.02 Jan 28 08:23:58 ifi-zen-stream ifi_metadata[1410]: [Info] MetaClient: MetaClientInit Jan 28 08:23:58 ifi-zen-stream ifi_metadata[1410]: Could not connect: Reason::CONNECTION_ERROR Jan 28 08:23:58 ifi-zen-stream play[1664]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jan 28 08:23:58 ifi-zen-stream sudo[1657]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:58 ifi-zen-stream play[1650]: output: volumio mixer: hw:0 Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Spotify Connect Service Started Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Volumio called home Jan 28 08:23:58 ifi-zen-stream sudo[1648]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:58 ifi-zen-stream node[475]: ConvSwitch: Add client spotify Jan 28 08:23:58 ifi-zen-stream node[477]: A new connection has been established. Jan 28 08:23:58 ifi-zen-stream sudo[1667]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:58 ifi-zen-stream node[477]: metadata_handler Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.563 I api SpRegisterDebugCallbacks [returned value: 0] Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.563 I api SpInit(0xffdb9660, API v66) Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.563 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.564 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.564 I esdk Successfully registered dns callback: dns_lookup_callback Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.564 I download Using 128 kB for cache buffer Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.564 I download Using 621 kB for compressed audio buffer Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.564 I esdk Successfully registered error callback Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.579 I esdk Creating IPv4 socket (domain 2) Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I esdk Creating IPv4 socket (domain 2) Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I api SpInit [returned value: 0] Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I api SpRegisterConnectionCallbacks(0xffdb9648, (nil)) Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I api SpRegisterConnectionCallbacks [returned value: 0] Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I api SpRegisterPlaybackCallbacks(0xffdb9630, (nil)) Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.580 I api SpRegisterPlaybackCallbacks [returned value: 0] Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.596 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Jan 28 08:23:58 ifi-zen-stream play[1650]: 2026-01-28 08:23:58.596 I app Initialized! Jan 28 08:23:58 ifi-zen-stream sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jan 28 08:23:58 ifi-zen-stream sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Jan 28 08:23:58 ifi-zen-stream volumio[1164]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Roon Ready playback start Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Starting Roon Ready metadata receiver Jan 28 08:23:58 ifi-zen-stream volumio[1164]: Error adding Membership: Error: addMembership EINVAL Jan 28 08:23:58 ifi-zen-stream systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jan 28 08:23:58 ifi-zen-stream node[477]: Closing connection with the client tidal-connect Jan 28 08:23:58 ifi-zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jan 28 08:23:58 ifi-zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jan 28 08:23:58 ifi-zen-stream systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jan 28 08:23:58 ifi-zen-stream systemd[1]: Stopping iFi Streamer Tidal Connect Service... Jan 28 08:23:58 ifi-zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Jan 28 08:23:58 ifi-zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Jan 28 08:23:58 ifi-zen-stream systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jan 28 08:23:58 ifi-zen-stream systemd[1]: Started iFi Streamer Tidal Connect Service. Jan 28 08:23:58 ifi-zen-stream sudo[1719]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:58 ifi-zen-stream systemd[1]: Started iFi Streamer Tidal Metadata Service. Jan 28 08:23:58 ifi-zen-stream node[477]: A new connection has been established. Jan 28 08:23:58 ifi-zen-stream node[477]: metadata_handler Jan 28 08:23:58 ifi-zen-stream systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 28 08:23:58 ifi-zen-stream systemd[1]: Started Daily apt upgrade and clean activities. Jan 28 08:23:58 ifi-zen-stream node[475]: ConvSwitch: Add client roon-ready Jan 28 08:23:58 ifi-zen-stream volumio[1164]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Shairport-Sync Stopped Jan 28 08:23:58 ifi-zen-stream volumio[1164]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Jan 28 08:23:58 ifi-zen-stream volumio[1164]: Job for mpd.socket canceled. Jan 28 08:23:58 ifi-zen-stream volumio[1164]: Job for mpd.service canceled. Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: Completed starting Core Plugins Jan 28 08:23:58 ifi-zen-stream volumio[1164]: [Metrics] CommandRouter: 11s 514.82ms Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::Close All Modals sent Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumiosetStartupVolume Jan 28 08:23:58 ifi-zen-stream sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:58 ifi-zen-stream sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: warning: volume is underfined, set volume to 100 Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: VolumeController::SetAlsaVolume100 Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:23:58 ifi-zen-stream systemd[1]: Listening on mpd.socket. Jan 28 08:23:58 ifi-zen-stream kernel: wm8804 0-003b: wm8804_hw_params rate: 0xac44 format: 0x2 Jan 28 08:23:58 ifi-zen-stream volumio[1164]: info: RoonReady Started Jan 28 08:23:58 ifi-zen-stream systemd[1]: Starting Music Player Daemon... Jan 28 08:23:58 ifi-zen-stream volumio[1164]: error: MPD error: Error: connect ENOENT /run/mpd/socket Jan 28 08:23:58 ifi-zen-stream volumio[1164]: error: connect ENOENT /run/mpd/socket {"errno":-2,"code":"ENOENT","syscall":"connect","address":"/run/mpd/socket"} Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 08:23:59 ifi-zen-stream kernel: dwmmc_rockchip ff520000.dwmmc: Unexpected data interrupt latency Jan 28 08:23:59 ifi-zen-stream sudo[1765]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 08:23:59 ifi-zen-stream sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:59 ifi-zen-stream sudo[1765]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 28 08:23:59 ifi-zen-stream sudo[1765]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: ConvSwitch: Get message: request:pause Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Tidal Connect Service Started Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Discovery: this is already registered, 38a04a19-e05c-47ad-8f76-2979ca254a12 Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Discovery: Found device ZEN Stream Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioGetState Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:59 ifi-zen-stream volumio[1164]: error: Could not retrieve plugin audio_interface multiroom Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Jan 28 08:23:59 ifi-zen-stream volumio[1164]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Roon Ready playback start Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Roon Ready metadata receiver is on Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Starting Shairport Sync Jan 28 08:23:59 ifi-zen-stream sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Starting Shairport Sync Jan 28 08:23:59 ifi-zen-stream sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:59 ifi-zen-stream sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 08:23:59 ifi-zen-stream sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:59 ifi-zen-stream sudo[1797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 08:23:59 ifi-zen-stream sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:23:59 ifi-zen-stream systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 08:23:59 ifi-zen-stream sudo[1793]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:59 ifi-zen-stream systemd[1]: Started iFi Streamer Qobuz Connect Service. Jan 28 08:23:59 ifi-zen-stream sudo[1791]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: ++++--++++ getState Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioGetState Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:23:59 ifi-zen-stream systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 08:23:59 ifi-zen-stream systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 28 08:23:59 ifi-zen-stream systemd[1]: shairport-sync.service: Succeeded. Jan 28 08:23:59 ifi-zen-stream systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 08:23:59 ifi-zen-stream systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 08:23:59 ifi-zen-stream sudo[1797]: pam_unix(sudo:session): session closed for user root Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Shairport-Sync Started Jan 28 08:23:59 ifi-zen-stream volumio[1164]: Error adding Membership: Error: addMembership EINVAL Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Qobuz Connect Service Started Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: Shairport-Sync Started Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Starting sample application Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Device Name: ZEN Stream Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Manufacturer: Sample manufacturer Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Model: iFi Streamer Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Serial Number: ifi:9a:43:3d:a8:4d:ac Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Audio output device: volumio Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Volume control device: default Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Volume control element: Master Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.952 [1819.1819] DEBUG SampleApp: Local config server port: 8000 Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.958 [1819.1819] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Jan 28 08:23:59 ifi-zen-stream play[1800]: 20260128 08:23:59.958 [1819.1819] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Jan 28 08:23:59 ifi-zen-stream play[1800]: [Info] MetaClient: MetaClient SDK -- v1.02 Jan 28 08:23:59 ifi-zen-stream node[477]: A new connection has been established. Jan 28 08:23:59 ifi-zen-stream node[477]: metadata_handler Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 28 08:23:59 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 08:24:00 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 28 08:24:00 ifi-zen-stream play[1800]: [Info] MetaClient: MetaClientInit Jan 28 08:24:00 ifi-zen-stream play[1800]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jan 28 08:24:00 ifi-zen-stream node[475]: ConvSwitch: Add client qobuz-connect Jan 28 08:24:00 ifi-zen-stream play[1800]: [Info] ConvSwitch: ConvSwitchInit Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.066 [1819.1819] DEBUG ActiveStateManager: [0xab005ff8]: Creating new Active State Manager Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.066 [1819.1819] DEBUG NetworkActivityTracker: [0xab006068]: Creating new Network Activity Tracker Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG EndpointManager: [0xab006fe0]: Creating Endpoint Manager Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG PlaybackSessionManager: [0xab0073d0]: Creating Playback Session Manager Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG BackoffTimer: [0xab007548]: Creating Backoff Timer Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG MediaEngine: [0xab007668]: Creating new Media Engine Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG AudioRenderer: [0xab007888]: Creating Audio Renderer Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] TRACE MediaEngineCommandSequencer: [0xab0079e0]: Creating Media Engine Command Sequencer Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG PlaybackControlsManager: [0xab007a40]: Creating new Playback Controls Manager Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG VolumeManager: [0xab007ad0]: Creating new Volume Manager Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG ProtocolHandler: [0xab007da8]: Creating Protocol Handler Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG MessageReceiver: [0xab007e48]: Creating Message Receiver Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG RendererActivationController: [0xab007fc0]: Creating new Renderer Activation Controller Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] TRACE RendererActivationCommandSequencer: [0xab008068]: Creating Renderer Activation Command Sequencer Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG ReportingManager: [0xab008178]: Creating new Reporting Manager Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG StreamingEventReporter: [0xab0081c0]: Creating Streaming Event Reporter Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG BackoffTimer: [0xab0082a0]: Creating Backoff Timer Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG TokenExpiryController: [0xab0083b0]: Creating Token Expiry Controller Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] DEBUG UserInactivityManager: [0xab008400]: Creating new User Inactivity Manager Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] INFO VolumeManager: [0xab007ad0]: Setting new playback volume: 100 Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.067 [1819.1819] INFO VolumeManager: [0xab007ad0]: Setting new mute state: 1 Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.068 [1819.1819] DEBUG SampleApp: Qobuz Connect has been initialized Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.068 [1819.1819] TRACE SampleApp: Running event loop Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.068 [1819.1819] INFO QobuzConnect: [0xab0084a0]: Client initialized! Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.068 [1819.1819] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream, service name: _qobuz-connect._tcp Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.086 [1819.1819] DEBUG SampleApp: Avahi client state changed: RUNNING Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.090 [1819.1819] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.097 [1819.1819] INFO LocalConfigManager: [0xab0073b0]: Starting Local Configuration server Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.097 [1819.1819] INFO SampleApp: Starting Local configuration server Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.099 [1819.1819] INFO SampleApp: Playback mute state changed: 1 Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.099 [1819.1819] ERROR SampleApp: Unable to set mute state, no ALSA volume element Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.099 [1819.1819] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.103 [1819.1819] INFO SampleApp: Playback volume changed: 100 Jan 28 08:24:00 ifi-zen-stream play[1800]: 20260128 08:24:00.104 [1819.1819] ERROR SampleApp: Unable to set volume, no ALSA volume element Jan 28 08:24:00 ifi-zen-stream node[475]: ConvSwitch: Drop client roon-ready Jan 28 08:24:00 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=exited, status=1/FAILURE Jan 28 08:24:00 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Failed with result 'exit-code'. Jan 28 08:24:00 ifi-zen-stream sudo[1834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jan 28 08:24:00 ifi-zen-stream sudo[1834]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:24:00 ifi-zen-stream play[1650]: 2026-01-28 08:24:00.638 E esdk select error: 4 -> Interrupted system call Jan 28 08:24:00 ifi-zen-stream play[1650]: 2026-01-28 08:24:00.640 I app Exiting with error = 0 Jan 28 08:24:00 ifi-zen-stream play[1650]: 2026-01-28 08:24:00.640 I api SpFree() Jan 28 08:24:00 ifi-zen-stream play[1650]: 2026-01-28 08:24:00.641 I api SpFree [returned value: 0] Jan 28 08:24:00 ifi-zen-stream play[1650]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jan 28 08:24:00 ifi-zen-stream play[1650]: [Info] ConvSwitch: ConvSwitchInit Jan 28 08:24:00 ifi-zen-stream play[1650]: [Info] MetaClient: MetaClient SDK -- v1.02 Jan 28 08:24:00 ifi-zen-stream play[1650]: [Info] MetaClient: MetaClientInit Jan 28 08:24:00 ifi-zen-stream play[1650]: [Info] ConvSwitch: ConvSwitchExit Jan 28 08:24:00 ifi-zen-stream play[1650]: [Info] MetaClient: MetaClientExit Jan 28 08:24:00 ifi-zen-stream node[475]: ConvSwitch: Drop client spotify Jan 28 08:24:00 ifi-zen-stream node[477]: Closing connection with the client spotify Jan 28 08:24:00 ifi-zen-stream systemd[1]: Stopping iFi Streamer Spotify Connect Service... Jan 28 08:24:00 ifi-zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Jan 28 08:24:00 ifi-zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Jan 28 08:24:00 ifi-zen-stream systemd[1]: Stopped iFi Streamer Spotify Connect Service. Jan 28 08:24:00 ifi-zen-stream systemd[1]: Started iFi Streamer Spotify Connect Service. Jan 28 08:24:00 ifi-zen-stream sudo[1834]: pam_unix(sudo:session): session closed for user root Jan 28 08:24:00 ifi-zen-stream volumio[1164]: info: Spotify Connect Service Started Jan 28 08:24:00 ifi-zen-stream play[1836]: output: volumio mixer: hw:0 Jan 28 08:24:00 ifi-zen-stream node[475]: ConvSwitch: Add client spotify Jan 28 08:24:00 ifi-zen-stream node[477]: A new connection has been established. Jan 28 08:24:00 ifi-zen-stream node[477]: metadata_handler Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.938 I api SpRegisterDebugCallbacks [returned value: 0] Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.938 I api SpInit(0xffa78430, API v66) Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.938 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.939 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.939 I esdk Successfully registered dns callback: dns_lookup_callback Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.939 I download Using 128 kB for cache buffer Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.939 I download Using 621 kB for compressed audio buffer Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.939 I esdk Successfully registered error callback Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.956 I esdk Creating IPv4 socket (domain 2) Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.956 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.957 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.957 I esdk Creating IPv4 socket (domain 2) Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.957 I api SpInit [returned value: 0] Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.957 I api SpRegisterConnectionCallbacks(0xffa78418, (nil)) Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.957 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.957 I api SpRegisterConnectionCallbacks [returned value: 0] Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.957 I api SpRegisterPlaybackCallbacks(0xffa78400, (nil)) Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.960 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.960 I api SpRegisterPlaybackCallbacks [returned value: 0] Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.968 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Jan 28 08:24:00 ifi-zen-stream play[1836]: 2026-01-28 08:24:00.968 I app Initialized! Jan 28 08:24:01 ifi-zen-stream play[1800]: 20260128 08:24:01.068 [1819.1819] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Jan 28 08:24:01 ifi-zen-stream sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 08:24:01 ifi-zen-stream sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:24:01 ifi-zen-stream sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 08:24:01 ifi-zen-stream sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:24:01 ifi-zen-stream sudo[1873]: pam_unix(sudo:session): session closed for user root Jan 28 08:24:01 ifi-zen-stream sudo[1874]: pam_unix(sudo:session): session closed for user root Jan 28 08:24:01 ifi-zen-stream node[475]: ConvSwitch: Add client tidal Jan 28 08:24:01 ifi-zen-stream sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart upmpdcli.service Jan 28 08:24:01 ifi-zen-stream sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 08:24:01 ifi-zen-stream tidal_connect_application[1870]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 08:24:01 ifi-zen-stream tidal_connect_application[1870]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 08:24:01 ifi-zen-stream tidal_connect_application[1870]: *** WARNING *** For more information see Jan 28 08:24:01 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Service RestartSec=1s expired, scheduling restart. Jan 28 08:24:01 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Scheduled restart job, restart counter is at 1. Jan 28 08:24:01 ifi-zen-stream systemd[1]: Stopped iFi Streamer Roon Ready Service. Jan 28 08:24:01 ifi-zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jan 28 08:24:01 ifi-zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jan 28 08:24:01 ifi-zen-stream systemd[1]: Starting UPnP Renderer front-end to MPD... Jan 28 08:24:01 ifi-zen-stream play[1887]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jan 28 08:24:01 ifi-zen-stream ifi_metadata[1743]: [Info] MetaClient: MetaClient SDK -- v1.02 Jan 28 08:24:01 ifi-zen-stream ifi_metadata[1743]: [Info] MetaClient: MetaClientInit Jan 28 08:24:01 ifi-zen-stream ifi_metadata[1743]: Connected Jan 28 08:24:01 ifi-zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jan 28 08:24:01 ifi-zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jan 28 08:24:01 ifi-zen-stream node[475]: ConvSwitch: Add client roon-ready Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: ConvSwitch: Get message: request:pause Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::serviceStop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::serviceStop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: Roon Ready Stop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: Jan 28 08:24:01 ifi-zen-stream volumio[1164]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: Roon Ready playback start Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:01 ifi-zen-stream volumio[1164]: info: Roon Ready metadata receiver is on Jan 28 08:24:03 ifi-zen-stream mpd[1773]: Jan 28 08:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 08:24:03 ifi-zen-stream systemd[1]: Started Music Player Daemon. Jan 28 08:24:03 ifi-zen-stream sudo[1761]: pam_unix(sudo:session): session closed for user root Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: Restarted MPD. Jan 28 08:24:03 ifi-zen-stream volumio[1164]: error: updateQueue error: null Jan 28 08:24:03 ifi-zen-stream node[475]: ConvSwitch: Drop client roon-ready Jan 28 08:24:03 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=exited, status=1/FAILURE Jan 28 08:24:03 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Failed with result 'exit-code'. Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: CoreStateMachine::serviceStop Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::serviceStop Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:03 ifi-zen-stream volumio[1164]: info: Roon Ready Stop Jan 28 08:24:04 ifi-zen-stream shairport-sync[1805]: warning: The output device "volumio" is busy and can't be used by Shairport Sync at present. Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Airplay started streaming, receiving metadatas Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: ConvSwitch: Sent Play Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Airplay started streaming, receiving metadatas Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: ConvSwitch: Sent Play Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: ConvSwitch: Get message: successsuccess Jan 28 08:24:04 ifi-zen-stream shairport-sync[1805]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 28 08:24:04 ifi-zen-stream shairport-sync[1805]: Dload Upload Total Spent Left Speed Jan 28 08:24:04 ifi-zen-stream shairport-sync[1805]: [158B blob data] Jan 28 08:24:04 ifi-zen-stream shairport-sync[1805]: Dload Upload Total Spent Left Speed Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Jan 28 08:24:04 ifi-zen-stream volumio[1164]: ---------------------------- Client requests AirplayActive Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Jan 28 08:24:04 ifi-zen-stream shairport-sync[1805]: [158B blob data] Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Updating volume from AirPlay: -24; 20% Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: VolumeController::SetAlsaVolume20 Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Jan 28 08:24:04 ifi-zen-stream volumio[1164]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Airplay playback start Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: ConvSwitch: Sent Play Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream shairport-sync[1805]: [136B blob data] Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::servicePushState Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::servicePushState Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::servicePushState Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CorePlayQueue::getTrack 0 Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: ConvSwitch: Get message: success Jan 28 08:24:04 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Service RestartSec=1s expired, scheduling restart. Jan 28 08:24:04 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Scheduled restart job, restart counter is at 2. Jan 28 08:24:04 ifi-zen-stream systemd[1]: Stopped iFi Streamer Roon Ready Service. Jan 28 08:24:04 ifi-zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jan 28 08:24:04 ifi-zen-stream play[1981]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jan 28 08:24:04 ifi-zen-stream node[475]: ConvSwitch: Add client roon-ready Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: ConvSwitch: Get message: request:pause Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Jan 28 08:24:04 ifi-zen-stream volumio[1164]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Roon Ready playback start Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioStop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::stop Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:24:04 ifi-zen-stream volumio[1164]: info: Roon Ready metadata receiver is on Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::servicePushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::servicePushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::servicePushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreStateMachine::pushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::volumioPushState Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:24:05 ifi-zen-stream shairport-sync[1805]: warning: The output device "volumio" is no longer busy and will be used by Shairport Sync. Jan 28 08:24:05 ifi-zen-stream kernel: wm8804 0-003b: wm8804_hw_params rate: 0xac44 format: 0x2 Jan 28 08:24:05 ifi-zen-stream volumio[1164]: info: BOOT COMPLETED Jan 28 08:24:06 ifi-zen-stream volumio[1164]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jan 28 08:24:06 ifi-zen-stream volumio[1164]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 08:24:06 ifi-zen-stream volumio[1164]: Error: Type object is not supported Jan 28 08:24:06 ifi-zen-stream volumio[1164]: at Config.assertSupportedType (/volumio/node_modules/v-conf/index.js:299:15) Jan 28 08:24:06 ifi-zen-stream volumio[1164]: at Config.addConfigValue (/volumio/node_modules/v-conf/index.js:275:10) Jan 28 08:24:06 ifi-zen-stream volumio[1164]: at ControllerAlsa.saveCurrentVolume (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2789:19) Jan 28 08:24:06 ifi-zen-stream volumio[1164]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1220:32) Jan 28 08:24:06 ifi-zen-stream volumio[1164]: at CoreCommandRouter.volumioupdatevolume (/volumio/app/index.js:191:8) Jan 28 08:24:06 ifi-zen-stream volumio[1164]: at Timeout._onTimeout (/volumio/app/index.js:387:12) Jan 28 08:24:06 ifi-zen-stream volumio[1164]: at listOnTimeout (internal/timers.js:557:17) Jan 28 08:24:06 ifi-zen-stream volumio[1164]: at processTimers (internal/timers.js:500:7) Jan 28 08:24:06 ifi-zen-stream volumio[1164]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 08:24:06 ifi-zen-stream node[475]: ConvSwitch: Drop client roon-ready Jan 28 08:24:06 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=exited, status=1/FAILURE Jan 28 08:24:06 ifi-zen-stream systemd[1]: ifi-streamer-roon-ready.service: Failed with result 'exit-code'. Jan 28 08:24:06 ifi-zen-stream systemd[1]: Started UPnP Renderer front-end to MPD. Jan 28 08:24:06 ifi-zen-stream sudo[1878]: pam_unix(sudo:session): session closed for user root Jan 28 08:24:06 ifi-zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jan 28 08:24:06 ifi-zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jan 28 08:24:06 ifi-zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jan 28 08:24:07 ifi-zen-stream sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 08:23 Jan 28 08:24:07 ifi-zen-stream sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b6b5fc9c726190e478d1307bf4816c8d6dfb5af2" VOLUMIO_FE_VERSION="607f0b2839009ad71a89496f82d0c60770a281b7" VOLUMIO_FE3_VERSION="c98f50471352dc221c4a2aa0af52effb0ef3ca54" VOLUMIO_BE_VERSION="a0b0b71b1190468adb5bea37ae28ea417ba4e5f1" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 31 19:06:26 CST 2025" VOLUMIO_VERSION="3.00.3" VOLUMIO_HARDWARE="zen-stream" VOLUMIO_DEVICENAME="ZEN Stream" VOLUMIO_HASH="3bbd4a158ba48434722867160220a086"