-- Logs begin at Sat 2025-11-15 07:41:13 UTC, end at Fri 2025-12-12 11:40:10 UTC. -- Dec 12 11:39:00 ifi-zen-stream3 node[461]: ConvSwitch: Add client roon-ready Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: ConvSwitch: Get message: request:pause Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::serviceStop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::serviceStop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::stop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand stop took 16 milliseconds Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 10 milliseconds Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 6 milliseconds Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: Roon Ready playback start Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: Roon Ready metadata receiver is on Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 5 Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 86ms Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 81ms Dec 12 11:39:00 ifi-zen-stream3 sudo[4026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 12 11:39:00 ifi-zen-stream3 sudo[4026]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:00 ifi-zen-stream3 node[476]: Closing connection with the client tidal-connect Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:00 ifi-zen-stream3 node[461]: ConvSwitch: Drop client tidal Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 12 11:39:00 ifi-zen-stream3 sudo[4026]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: Tidal Connect Service Started Dec 12 11:39:00 ifi-zen-stream3 sudo[4029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 12 11:39:00 ifi-zen-stream3 sudo[4029]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:00 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 12 11:39:00 ifi-zen-stream3 node[476]: Closing connection with the client tidal-connect Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 12 11:39:00 ifi-zen-stream3 sudo[4029]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 12 11:39:00 ifi-zen-stream3 volumio[1437]: info: Tidal Connect Service Started Dec 12 11:39:00 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:00 ifi-zen-stream3 sudo[4047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 12 11:39:00 ifi-zen-stream3 sudo[4047]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:00 ifi-zen-stream3 sudo[4052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 12 11:39:00 ifi-zen-stream3 sudo[4052]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:00 ifi-zen-stream3 systemd[1]: Started iFi Streamer Qobuz Connect Service. Dec 12 11:39:00 ifi-zen-stream3 sudo[4047]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:01 ifi-zen-stream3 volumio[1437]: info: Qobuz Connect Service Started Dec 12 11:39:01 ifi-zen-stream3 sudo[4052]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:01 ifi-zen-stream3 volumio[1437]: info: Qobuz Connect Service Started Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.147 [4072.4072] DEBUG SampleApp: Starting sample application Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.147 [4072.4072] DEBUG SampleApp: Device Name: ZEN Stream3 Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.147 [4072.4072] DEBUG SampleApp: Manufacturer: Sample manufacturer Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.147 [4072.4072] DEBUG SampleApp: Model: iFi Streamer Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.147 [4072.4072] DEBUG SampleApp: Serial Number: ifi:32:d8:07:42:e3:7c Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.147 [4072.4072] DEBUG SampleApp: Audio output device: volumio Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.147 [4072.4072] DEBUG SampleApp: Volume control device: default Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.148 [4072.4072] DEBUG SampleApp: Volume control element: Master Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.148 [4072.4072] DEBUG SampleApp: Local config server port: 8000 Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.152 [4072.4072] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.153 [4072.4072] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Dec 12 11:39:01 ifi-zen-stream3 play[4055]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:01 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:01 ifi-zen-stream3 play[4055]: [Info] MetaClient: MetaClientInit Dec 12 11:39:01 ifi-zen-stream3 play[4055]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 12 11:39:01 ifi-zen-stream3 node[461]: ConvSwitch: Add client qobuz-connect Dec 12 11:39:01 ifi-zen-stream3 sudo[4075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 12 11:39:01 ifi-zen-stream3 sudo[4075]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 12 11:39:01 ifi-zen-stream3 play[4055]: [Info] ConvSwitch: ConvSwitchInit Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.260 [4072.4072] DEBUG ActiveStateManager: [0xab7dd098]: Creating new Active State Manager Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.260 [4072.4072] DEBUG NetworkActivityTracker: [0xab7dd108]: Creating new Network Activity Tracker Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG EndpointManager: [0xab7de080]: Creating Endpoint Manager Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG PlaybackSessionManager: [0xab7de470]: Creating Playback Session Manager Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG BackoffTimer: [0xab7de5e8]: Creating Backoff Timer Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG MediaEngine: [0xab7de708]: Creating new Media Engine Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG AudioRenderer: [0xab7de928]: Creating Audio Renderer Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] TRACE MediaEngineCommandSequencer: [0xab7dea80]: Creating Media Engine Command Sequencer Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG PlaybackControlsManager: [0xab7deae0]: Creating new Playback Controls Manager Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG VolumeManager: [0xab7deb70]: Creating new Volume Manager Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG ProtocolHandler: [0xab7dee48]: Creating Protocol Handler Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG MessageReceiver: [0xab7deee8]: Creating Message Receiver Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG RendererActivationController: [0xab7df060]: Creating new Renderer Activation Controller Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] TRACE RendererActivationCommandSequencer: [0xab7df108]: Creating Renderer Activation Command Sequencer Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG ReportingManager: [0xab7df218]: Creating new Reporting Manager Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG StreamingEventReporter: [0xab7df260]: Creating Streaming Event Reporter Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG BackoffTimer: [0xab7df340]: Creating Backoff Timer Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG TokenExpiryController: [0xab7df450]: Creating Token Expiry Controller Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] DEBUG UserInactivityManager: [0xab7df4a0]: Creating new User Inactivity Manager Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Stopping Music Player Daemon... Dec 12 11:39:01 ifi-zen-stream3 sudo[4079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] INFO VolumeManager: [0xab7deb70]: Setting new playback volume: 100 Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.261 [4072.4072] INFO VolumeManager: [0xab7deb70]: Setting new mute state: 1 Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.262 [4072.4072] DEBUG SampleApp: Qobuz Connect has been initialized Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.262 [4072.4072] TRACE SampleApp: Running event loop Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.262 [4072.4072] INFO QobuzConnect: [0xab7df540]: Client initialized! Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.262 [4072.4072] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream3, service name: _qobuz-connect._tcp Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.283 [4072.4072] DEBUG SampleApp: Avahi client state changed: RUNNING Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.287 [4072.4072] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Dec 12 11:39:01 ifi-zen-stream3 volumio[1437]: MPD server connection closed Dec 12 11:39:01 ifi-zen-stream3 sudo[4079]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.296 [4072.4072] INFO LocalConfigManager: [0xab7de450]: Starting Local Configuration server Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.296 [4072.4072] INFO SampleApp: Starting Local configuration server Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.297 [4072.4072] INFO SampleApp: Playback mute state changed: 1 Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.297 [4072.4072] ERROR SampleApp: Unable to set mute state, no ALSA volume element Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.298 [4072.4072] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.299 [4072.4072] INFO SampleApp: Playback volume changed: 100 Dec 12 11:39:01 ifi-zen-stream3 play[4055]: 20251212 11:39:01.299 [4072.4072] ERROR SampleApp: Unable to set volume, no ALSA volume element Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 12 11:39:01 ifi-zen-stream3 volumio[1437]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Samsung_USB Dec 12 11:39:01 ifi-zen-stream3 sudo[4075]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:01 ifi-zen-stream3 volumio[1437]: error: Service socket error: Error: This socket has been ended by the other party Dec 12 11:39:01 ifi-zen-stream3 sudo[4084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:01 ifi-zen-stream3 sudo[4084]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:01 ifi-zen-stream3 sudo[4079]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 12 11:39:01 ifi-zen-stream3 sudo[4087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:01 ifi-zen-stream3 sudo[4087]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Stopping mpd.socket. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Failed to listen on mpd.socket. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 12 11:39:01 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 12 11:39:01 ifi-zen-stream3 sudo[4090]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 11:39:01 ifi-zen-stream3 sudo[4090]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:01 ifi-zen-stream3 sudo[4090]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:01 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:02 ifi-zen-stream3 play[4055]: 20251212 11:39:02.088 [4072.4072] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 12 11:39:02 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 12 11:39:02 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 12 11:39:02 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 12 11:39:03 ifi-zen-stream3 node[461]: ConvSwitch: Add client tidal Dec 12 11:39:03 ifi-zen-stream3 tidal_connect_application[4153]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 12 11:39:03 ifi-zen-stream3 tidal_connect_application[4153]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 12 11:39:03 ifi-zen-stream3 tidal_connect_application[4153]: *** WARNING *** For more information see Dec 12 11:39:03 ifi-zen-stream3 ifi_metadata[4038]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:03 ifi-zen-stream3 ifi_metadata[4038]: [Info] MetaClient: MetaClientInit Dec 12 11:39:03 ifi-zen-stream3 ifi_metadata[4038]: Connected Dec 12 11:39:03 ifi-zen-stream3 kernel: usb 5-1.1: new SuperSpeed USB device number 4 using xhci-hcd Dec 12 11:39:03 ifi-zen-stream3 kernel: usb 5-1.1: New USB device found, idVendor=04e8, idProduct=6300 Dec 12 11:39:03 ifi-zen-stream3 kernel: usb 5-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 12 11:39:03 ifi-zen-stream3 kernel: usb 5-1.1: Product: Flash Drive Dec 12 11:39:03 ifi-zen-stream3 kernel: usb 5-1.1: Manufacturer: Samsung Dec 12 11:39:03 ifi-zen-stream3 kernel: usb 5-1.1: SerialNumber: 0362025080001663 Dec 12 11:39:03 ifi-zen-stream3 kernel: usb-storage 5-1.1:1.0: USB Mass Storage device detected Dec 12 11:39:03 ifi-zen-stream3 kernel: scsi host1: usb-storage 5-1.1:1.0 Dec 12 11:39:03 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:03 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:03 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:03 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:03 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:03 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:04 ifi-zen-stream3 mpd[4092]: Dec 12 11:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 11:39:04 ifi-zen-stream3 systemd[1]: Started Music Player Daemon. Dec 12 11:39:04 ifi-zen-stream3 sudo[4084]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:04 ifi-zen-stream3 sudo[4087]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: Scanning removed location : "USB/Samsung_USB" Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::getcurrentVolume Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioRetrievevolume Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:04 ifi-zen-stream3 sudo[4175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:04 ifi-zen-stream3 sudo[4175]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:04 ifi-zen-stream3 sudo[4177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: VolumeController::SetAlsaVolume100 Dec 12 11:39:04 ifi-zen-stream3 sudo[4177]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:04 ifi-zen-stream3 play[2451]: 2025-12-12 11:39:04.295 E esdk select error: 4 -> Interrupted system call Dec 12 11:39:04 ifi-zen-stream3 play[2451]: 2025-12-12 11:39:04.296 I app Exiting with error = 0 Dec 12 11:39:04 ifi-zen-stream3 play[2451]: 2025-12-12 11:39:04.296 I api SpFree() Dec 12 11:39:04 ifi-zen-stream3 play[2451]: 2025-12-12 11:39:04.296 I api SpFree [returned value: 0] Dec 12 11:39:04 ifi-zen-stream3 play[2451]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 12 11:39:04 ifi-zen-stream3 play[2451]: [Info] ConvSwitch: ConvSwitchInit Dec 12 11:39:04 ifi-zen-stream3 play[2451]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:04 ifi-zen-stream3 play[2451]: [Info] MetaClient: MetaClientInit Dec 12 11:39:04 ifi-zen-stream3 play[2451]: [Info] ConvSwitch: ConvSwitchExit Dec 12 11:39:04 ifi-zen-stream3 play[2451]: [Info] MetaClient: MetaClientExit Dec 12 11:39:04 ifi-zen-stream3 node[461]: ConvSwitch: Drop client spotify Dec 12 11:39:04 ifi-zen-stream3 node[476]: Closing connection with the client spotify Dec 12 11:39:04 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 12 11:39:04 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:04 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 12 11:39:04 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: Job for mpd.socket failed. Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: Restarted MPD. Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: error: MPD error: The expression evaluated to a falsy value: Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: assert.ok(self.idling) Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: error: The expression evaluated to a falsy value: Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: assert.ok(self.idling) Dec 12 11:39:04 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: error: updateQueue error: null Dec 12 11:39:04 ifi-zen-stream3 sudo[4175]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: Spotify Connect Service Started Dec 12 11:39:04 ifi-zen-stream3 sudo[4177]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: Spotify Connect Service Started Dec 12 11:39:04 ifi-zen-stream3 play[4180]: output: volumio mixer: hw:0 Dec 12 11:39:04 ifi-zen-stream3 node[461]: ConvSwitch: Add client spotify Dec 12 11:39:04 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.551 I api SpRegisterDebugCallbacks [returned value: 0] Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.551 I api SpInit(0xff99f100, API v66) Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.551 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.552 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 Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.552 I esdk Successfully registered dns callback: dns_lookup_callback Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.552 I download Using 128 kB for cache buffer Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.552 I download Using 621 kB for compressed audio buffer Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.552 I esdk Successfully registered error callback Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.563 I esdk Creating IPv4 socket (domain 2) Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I esdk Creating IPv4 socket (domain 2) Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I api SpInit [returned value: 0] Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I api SpRegisterConnectionCallbacks(0xff99f0e8, (nil)) Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I api SpRegisterPlaybackCallbacks(0xff99f0d0, (nil)) Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.564 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.569 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 12 11:39:04 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:04.569 I app Initialized! Dec 12 11:39:04 ifi-zen-stream3 kernel: scsi 1:0:0:0: Direct-Access Samsung Flash Drive 1100 PQ: 0 ANSI: 6 Dec 12 11:39:04 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:04 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:04 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:04 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:04 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:04 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:04 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:04 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] 125313283 512-byte logical blocks: (64.2 GB/59.8 GiB) Dec 12 11:39:04 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] Write Protect is off Dec 12 11:39:04 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] Mode Sense: 43 00 00 00 Dec 12 11:39:04 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Dec 12 11:39:04 ifi-zen-stream3 kernel: sda: sda1 Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::serviceStop Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::serviceStop Dec 12 11:39:04 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] Attached SCSI removable disk Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: Roon Ready Stop Dec 12 11:39:04 ifi-zen-stream3 ntpd[1071]: receive: Unexpected origin timestamp 0xece67d58.df2b7508 does not match aorg 0000000000.00000000 from server@119.28.183.184 xmt 0xece67d58.dfafca27 Dec 12 11:39:04 ifi-zen-stream3 volumio[1437]: info: Mounting Device Samsung_USB Dec 12 11:39:04 ifi-zen-stream3 sudo[4211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Samsung_USB -o noatime Dec 12 11:39:04 ifi-zen-stream3 sudo[4211]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:05 ifi-zen-stream3 mount.exfat[4213]: volume was not unmounted cleanly Dec 12 11:39:05 ifi-zen-stream3 sudo[4211]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: WARN: volume was not unmounted cleanly. Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Scanning new location : "USB/Samsung_USB" Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: update Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: update Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 18 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 14 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 12 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 8 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Command Router : Notfying DB Updatetrue Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: networkfs , getMountFileCnt Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMountFileCnt] in plugin networkfs Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Command Router : Notfying DB Updatetrue Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 178ms Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 171ms Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: MPD Database updated - AlbumList cache refreshed Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: database Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: update Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: MPD Database updated - AlbumList cache refreshed Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: database Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: update Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 29 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 26 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 25 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 18 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 15 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 14 milliseconds Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Command Router : Notfying DB Updatefalse Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: Command Router : Notfying DB Updatefalse Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 5 Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 198ms Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 198ms Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 185ms Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 184ms Dec 12 11:39:05 ifi-zen-stream3 volumio[1437]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/TrondheimSolistene/DIVERTIMENTI/2bbba838-b705-485c-a549-19fd9021e343.jpg' Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: new high-speed USB device number 5 using xhci-hcd Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: New USB device found, idVendor=20b1, idProduct=3009 Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: Product: ZEN DAC 3 Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: Manufacturer: iFi (by AMR) Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: SerialNumber: 005A Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: Warning! Unlikely big volume range (=32512), cval->res is probably wrong. Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: [10] FU [ZEN DAC 3 Playback Volume] ch = 2, val = -32512/0/1 Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: Warning! Unlikely big volume range (=32512), cval->res is probably wrong. Dec 12 11:39:07 ifi-zen-stream3 kernel: usb 4-1.3: [10] FU [ZEN DAC 3 Playback Volume] ch = 1, val = -32512/0/1 Dec 12 11:39:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: hid-generic 0003:20B1:3009.0003: hiddev0,hidraw0: USB HID v1.10 Device [iFi (by AMR) ZEN DAC 3] on usb-xhci-hcd.0.auto-1.3/input3 Dec 12 11:39:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:08 ifi-zen-stream3 systemd-udevd[3889]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Dec 12 11:39:08 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: ---------------------------- USB Audio Device Attached Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::Close All Modals sent Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Preparing to save Alsa Options, stopping services first Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPause Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pause Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Saving Audio Output to: {"disallowPush":false,"output_device":{"value":"5","label":"ZEN DAC 3"}} Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , forceDoP Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: ZEN DAC 3: Supports DSD Native. Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Ignoring Mixers Options Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Device 5 does not have any Mixer Control Available Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Restoring Previous Volume level: 100 false false Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Updating Volume Controller Parameters: Device: 5 Name: ZEN DAC 3 Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 5 Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Preparing to generate the ALSA configuration file Dec 12 11:39:08 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 12 11:39:08 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: wizard , refreshWizarOutputDevices Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Asound.conf file written Dec 12 11:39:08 ifi-zen-stream3 sudo[4305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 12 11:39:08 ifi-zen-stream3 sudo[4305]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:08 ifi-zen-stream3 sudo[4305]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: No state is present for card rkifispdif Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: Found hardware: "rk-ifi-spdif" "" "" "" "" Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: Hardware is initialized using a generic method Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: No state is present for card rkifispdif Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: No state is present for card Z3 Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3009" "" "" Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: Hardware is initialized using a generic method Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: No state is present for card Z3 Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Tidal connect: Output device has changed Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Output device has changed, restarting MPD Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Qobuz connect: Output device has changed Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Roon Ready: Output device has changed Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Spotify: Output device has changed Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Tidal connect: Output device has changed Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Output device has changed, restarting MPD Dec 12 11:39:08 ifi-zen-stream3 sudo[4310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 11:39:08 ifi-zen-stream3 sudo[4310]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:08 ifi-zen-stream3 sudo[4310]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Qobuz connect: Output device has changed Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Roon Ready: Output device has changed Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: Spotify: Output device has changed Dec 12 11:39:08 ifi-zen-stream3 sudo[4317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 11:39:08 ifi-zen-stream3 sudo[4317]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: MPD Permissions set Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 sudo[4317]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:08 ifi-zen-stream3 volumio[1437]: info: MPD Permissions set Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: Starting RoonReady Sync Dec 12 11:39:09 ifi-zen-stream3 sudo[4337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 12 11:39:09 ifi-zen-stream3 sudo[4337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: Starting RoonReady Sync Dec 12 11:39:09 ifi-zen-stream3 sudo[4338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 12 11:39:09 ifi-zen-stream3 sudo[4338]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Dec 12 11:39:09 ifi-zen-stream3 sudo[4341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 12 11:39:09 ifi-zen-stream3 node[476]: Closing connection with the client qobuz-connect Dec 12 11:39:09 ifi-zen-stream3 node[461]: ConvSwitch: Drop client qobuz-connect Dec 12 11:39:09 ifi-zen-stream3 sudo[4341]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Dec 12 11:39:09 ifi-zen-stream3 sudo[4345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 12 11:39:09 ifi-zen-stream3 sudo[4345]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:09 ifi-zen-stream3 sudo[4337]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:09 ifi-zen-stream3 node[476]: Closing connection with the client roon-ready Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Roon Ready Service... Dec 12 11:39:09 ifi-zen-stream3 node[461]: ConvSwitch: Drop client roon-ready Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Roon Ready Service. Dec 12 11:39:09 ifi-zen-stream3 sudo[4341]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:09 ifi-zen-stream3 systemd[1]: Started iFi Streamer Roon Ready Service. Dec 12 11:39:09 ifi-zen-stream3 sudo[4338]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:09 ifi-zen-stream3 sudo[4345]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: RoonReady Started Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: RoonReady Started Dec 12 11:39:09 ifi-zen-stream3 play[4347]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Dec 12 11:39:09 ifi-zen-stream3 node[461]: ConvSwitch: Add client roon-ready Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: ConvSwitch: Get message: request:pause Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: Roon Ready playback start Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:09 ifi-zen-stream3 volumio[1437]: info: Roon Ready metadata receiver is on Dec 12 11:39:10 ifi-zen-stream3 sudo[4387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 12 11:39:10 ifi-zen-stream3 sudo[4387]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 12 11:39:10 ifi-zen-stream3 node[476]: Closing connection with the client tidal-connect Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:10 ifi-zen-stream3 node[461]: ConvSwitch: Drop client tidal Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 12 11:39:10 ifi-zen-stream3 sudo[4390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 12 11:39:10 ifi-zen-stream3 sudo[4387]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:10 ifi-zen-stream3 sudo[4390]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 12 11:39:10 ifi-zen-stream3 volumio[1437]: info: Tidal Connect Service Started Dec 12 11:39:10 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 12 11:39:10 ifi-zen-stream3 node[476]: Closing connection with the client tidal-connect Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 12 11:39:10 ifi-zen-stream3 sudo[4390]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 12 11:39:10 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:10 ifi-zen-stream3 volumio[1437]: info: Tidal Connect Service Started Dec 12 11:39:10 ifi-zen-stream3 sudo[4405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 12 11:39:10 ifi-zen-stream3 sudo[4405]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Started iFi Streamer Qobuz Connect Service. Dec 12 11:39:10 ifi-zen-stream3 sudo[4413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 12 11:39:10 ifi-zen-stream3 sudo[4405]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:10 ifi-zen-stream3 sudo[4413]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:10 ifi-zen-stream3 volumio[1437]: info: Qobuz Connect Service Started Dec 12 11:39:10 ifi-zen-stream3 sudo[4413]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:10 ifi-zen-stream3 volumio[1437]: info: Qobuz Connect Service Started Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.546 [4436.4436] DEBUG SampleApp: Starting sample application Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.547 [4436.4436] DEBUG SampleApp: Device Name: ZEN Stream3 Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.547 [4436.4436] DEBUG SampleApp: Manufacturer: Sample manufacturer Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.547 [4436.4436] DEBUG SampleApp: Model: iFi Streamer Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.547 [4436.4436] DEBUG SampleApp: Serial Number: ifi:32:d8:07:42:e3:7c Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.547 [4436.4436] DEBUG SampleApp: Audio output device: volumio Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.547 [4436.4436] DEBUG SampleApp: Volume control device: default Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.547 [4436.4436] DEBUG SampleApp: Volume control element: Master Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.547 [4436.4436] DEBUG SampleApp: Local config server port: 8000 Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.551 [4436.4436] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.552 [4436.4436] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Dec 12 11:39:10 ifi-zen-stream3 play[4414]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:10 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:10 ifi-zen-stream3 play[4414]: [Info] MetaClient: MetaClientInit Dec 12 11:39:10 ifi-zen-stream3 play[4414]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 12 11:39:10 ifi-zen-stream3 node[461]: ConvSwitch: Add client qobuz-connect Dec 12 11:39:10 ifi-zen-stream3 play[4414]: [Info] ConvSwitch: ConvSwitchInit Dec 12 11:39:10 ifi-zen-stream3 sudo[4440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.659 [4436.4436] DEBUG ActiveStateManager: [0xab9d3030]: Creating new Active State Manager Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.659 [4436.4436] DEBUG NetworkActivityTracker: [0xab9d30a0]: Creating new Network Activity Tracker Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG EndpointManager: [0xab9d4018]: Creating Endpoint Manager Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG PlaybackSessionManager: [0xab9d4408]: Creating Playback Session Manager Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG BackoffTimer: [0xab9d4580]: Creating Backoff Timer Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG MediaEngine: [0xab9d46a0]: Creating new Media Engine Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG AudioRenderer: [0xab9d48c0]: Creating Audio Renderer Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] TRACE MediaEngineCommandSequencer: [0xab9d4a18]: Creating Media Engine Command Sequencer Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG PlaybackControlsManager: [0xab9d4a78]: Creating new Playback Controls Manager Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG VolumeManager: [0xab9d4b08]: Creating new Volume Manager Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG ProtocolHandler: [0xab9d4de0]: Creating Protocol Handler Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG MessageReceiver: [0xab9d4e80]: Creating Message Receiver Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG RendererActivationController: [0xab9d4ff8]: Creating new Renderer Activation Controller Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] TRACE RendererActivationCommandSequencer: [0xab9d50a0]: Creating Renderer Activation Command Sequencer Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG ReportingManager: [0xab9d51b0]: Creating new Reporting Manager Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG StreamingEventReporter: [0xab9d51f8]: Creating Streaming Event Reporter Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.660 [4436.4436] DEBUG BackoffTimer: [0xab9d52d8]: Creating Backoff Timer Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.661 [4436.4436] DEBUG TokenExpiryController: [0xab9d53e8]: Creating Token Expiry Controller Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.661 [4436.4436] DEBUG UserInactivityManager: [0xab9d5438]: Creating new User Inactivity Manager Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.661 [4436.4436] INFO VolumeManager: [0xab9d4b08]: Setting new playback volume: 100 Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.661 [4436.4436] INFO VolumeManager: [0xab9d4b08]: Setting new mute state: 1 Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.661 [4436.4436] DEBUG SampleApp: Qobuz Connect has been initialized Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.661 [4436.4436] TRACE SampleApp: Running event loop Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.661 [4436.4436] INFO QobuzConnect: [0xab9d54d8]: Client initialized! Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.661 [4436.4436] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream3, service name: _qobuz-connect._tcp Dec 12 11:39:10 ifi-zen-stream3 sudo[4440]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.684 [4436.4436] DEBUG SampleApp: Avahi client state changed: RUNNING Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.689 [4436.4436] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.698 [4436.4436] INFO LocalConfigManager: [0xab9d43e8]: Starting Local Configuration server Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.698 [4436.4436] INFO SampleApp: Starting Local configuration server Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.699 [4436.4436] INFO SampleApp: Playback mute state changed: 1 Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.700 [4436.4436] ERROR SampleApp: Unable to set mute state, no ALSA volume element Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.700 [4436.4436] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.700 [4436.4436] INFO SampleApp: Playback volume changed: 100 Dec 12 11:39:10 ifi-zen-stream3 play[4414]: 20251212 11:39:10.700 [4436.4436] ERROR SampleApp: Unable to set volume, no ALSA volume element Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopping Music Player Daemon... Dec 12 11:39:10 ifi-zen-stream3 sudo[4447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 12 11:39:10 ifi-zen-stream3 sudo[4447]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 12 11:39:10 ifi-zen-stream3 sudo[4440]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:10 ifi-zen-stream3 sudo[4447]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:10 ifi-zen-stream3 sudo[4452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:10 ifi-zen-stream3 sudo[4452]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 12 11:39:10 ifi-zen-stream3 sudo[4455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:10 ifi-zen-stream3 sudo[4455]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopping mpd.socket. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Failed to listen on mpd.socket. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 12 11:39:10 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 12 11:39:11 ifi-zen-stream3 sudo[4460]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 11:39:11 ifi-zen-stream3 sudo[4460]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:11 ifi-zen-stream3 sudo[4460]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:11 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:11 ifi-zen-stream3 play[4414]: 20251212 11:39:11.565 [4436.4436] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 12 11:39:12 ifi-zen-stream3 sudo[4467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 12 11:39:12 ifi-zen-stream3 sudo[4467]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:12 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:12.208 E esdk select error: 4 -> Interrupted system call Dec 12 11:39:12 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:12.209 I app Exiting with error = 0 Dec 12 11:39:12 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:12.210 I api SpFree() Dec 12 11:39:12 ifi-zen-stream3 play[4180]: 2025-12-12 11:39:12.210 I api SpFree [returned value: 0] Dec 12 11:39:12 ifi-zen-stream3 play[4180]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 12 11:39:12 ifi-zen-stream3 play[4180]: [Info] ConvSwitch: ConvSwitchInit Dec 12 11:39:12 ifi-zen-stream3 play[4180]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:12 ifi-zen-stream3 play[4180]: [Info] MetaClient: MetaClientInit Dec 12 11:39:12 ifi-zen-stream3 play[4180]: [Info] ConvSwitch: ConvSwitchExit Dec 12 11:39:12 ifi-zen-stream3 play[4180]: [Info] MetaClient: MetaClientExit Dec 12 11:39:12 ifi-zen-stream3 node[461]: ConvSwitch: Drop client spotify Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 12 11:39:12 ifi-zen-stream3 node[476]: Closing connection with the client spotify Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::getcurrentVolume Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioRetrievevolume Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 12 11:39:12 ifi-zen-stream3 sudo[4467]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:12 ifi-zen-stream3 sudo[4470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 12 11:39:12 ifi-zen-stream3 sudo[4470]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: Spotify Connect Service Started Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 12 11:39:12 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 12 11:39:12 ifi-zen-stream3 sudo[4470]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: Spotify Connect Service Started Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: hid-generic 0003:20B1:3009.0004: hiddev0,hidraw0: USB HID v1.10 Device [iFi (by AMR) ZEN DAC 3] on usb-xhci-hcd.0.auto-1.3/input3 Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 play[4475]: output: volumio mixer: hw:5 Dec 12 11:39:12 ifi-zen-stream3 node[461]: ConvSwitch: Add client spotify Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.590 I api SpRegisterDebugCallbacks [returned value: 0] Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.590 I api SpInit(0xffbd7af0, API v66) Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.590 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.591 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 Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.591 I esdk Successfully registered dns callback: dns_lookup_callback Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.591 I download Using 128 kB for cache buffer Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.591 I download Using 621 kB for compressed audio buffer Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.591 I esdk Successfully registered error callback Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.620 I esdk Creating IPv4 socket (domain 2) Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.620 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.620 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.620 I esdk Creating IPv4 socket (domain 2) Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.620 I api SpInit [returned value: 0] Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.621 I api SpRegisterConnectionCallbacks(0xffbd7ad8, (nil)) Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.621 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.621 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.621 I api SpRegisterPlaybackCallbacks(0xffbd7ac0, (nil)) Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.621 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.621 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.627 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 12 11:39:12 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:12.627 I app Initialized! Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: VolumeController::SetAlsaVolume100 Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:12 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 12 11:39:12 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 12 11:39:12 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 12 11:39:13 ifi-zen-stream3 node[461]: ConvSwitch: Add client tidal Dec 12 11:39:13 ifi-zen-stream3 tidal_connect_application[4539]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 12 11:39:13 ifi-zen-stream3 tidal_connect_application[4539]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 12 11:39:13 ifi-zen-stream3 tidal_connect_application[4539]: *** WARNING *** For more information see Dec 12 11:39:13 ifi-zen-stream3 ifi_metadata[4399]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:13 ifi-zen-stream3 ifi_metadata[4399]: [Info] MetaClient: MetaClientInit Dec 12 11:39:13 ifi-zen-stream3 ifi_metadata[4399]: Connected Dec 12 11:39:14 ifi-zen-stream3 mpd[4462]: Dec 12 11:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 11:39:14 ifi-zen-stream3 systemd[1]: Started Music Player Daemon. Dec 12 11:39:14 ifi-zen-stream3 sudo[4452]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:14 ifi-zen-stream3 sudo[4455]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: Restarted MPD. Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: Job for mpd.socket failed. Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: error: MPD error: The expression evaluated to a falsy value: Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: assert.ok(self.idling) Dec 12 11:39:14 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: error: The expression evaluated to a falsy value: Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: assert.ok(self.idling) Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: error: updateQueue error: null Dec 12 11:39:14 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 12 11:39:14 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::serviceStop Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::serviceStop Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:14 ifi-zen-stream3 volumio[1437]: info: Roon Ready Stop Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ++++--++++ playItemsList: {"item":{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","albumart":"/albumart?cacheid=511_1765539470979&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},"list":[{"service":"mpd","type":"song","title":"(352kHz)Divertimento 352.8-24","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac","albumart":"/albumart?cacheid=511_1765539470978&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"(48kHz)Divertimento 48-24.flac","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","albumart":"/albumart?cacheid=511_1765539470979&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac","artist":"","album":"","uri":"music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac","albumart":"/albumart?cacheid=511_1765539470979&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","albumart":"/albumart?cacheid=511_1765539470979&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 96kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac","albumart":"/albumart?cacheid=511_1765539470979&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","albumart":"/albumart?cacheid=511_1765539470979&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree (192 kHz)","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac","albumart":"/albumart?cacheid=511_1765539470980&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac","albumart":"/albumart?cacheid=511_1765539470980&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Hopak [TCHAIKOVSKY: Mazeppa] 176kHz","artist":"Minnesota Orchestra, Eiji Oue","album":"Exotic Dances From The Opera","uri":"music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac","albumart":"/albumart?cacheid=511_1765539470980&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"},{"service":"mpd","type":"song","title":"Carmen 44kHz","artist":"Carmen","album":"RMAF 2010","uri":"music-library/USB/Samsung_USB/歌曲/Carmen 44.flac","albumart":"/albumart?cacheid=511_1765539470980&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"}],"index":3} Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Preload queue cleared Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::ClearQueue Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::clearPlayQueue Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::saveQueue Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::addQueueItems Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::addQueueItems Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Preload queue cleared Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::saveQueue Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ConvSwitch: Sent Play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPlay Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::play index 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::addQueueItems Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::addQueueItems Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Preload queue cleared Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushQueue Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::saveQueue Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::play index undefined Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::startPlaybackTimer Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ConvSwitch: Get message: success Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand stop took 12 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces system playlist update Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Ignoring MPD Status Update Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand clear took 7 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac" Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces system playlist update Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Ignoring MPD Status Update Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: error: updateQueue error: null Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces system playlist update Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Ignoring MPD Status Update Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 12ms Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand add "USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac" took 9 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 8ms Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces system playlist update Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Ignoring MPD Status Update Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 17ms Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand play took 12 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 15ms Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 90 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 105 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","trackType":"flac"} Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState currentStatus stop Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 136ms Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 23 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 5 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":142,"duration":182,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1273 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","trackType":"flac"} Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 177ms Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 55 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":182,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1364 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","trackType":"flac"} Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":182,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1373 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","trackType":"flac"} Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState stateService play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState currentStatus play Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: Received an update from plugin. extracting info from payload Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 169ms Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 141ms Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/TrondheimSolistene/DIVERTIMENTI/2bbba838-b705-485c-a549-19fd9021e343.jpg' Dec 12 11:39:15 ifi-zen-stream3 volumio[1437]: modalDone Dec 12 11:39:16 ifi-zen-stream3 volumio[1437]: info: MetaClient: Sent Meta Dec 12 11:39:16 ifi-zen-stream3 volumio[1437]: info: MetaClient: Get message: success Dec 12 11:39:19 ifi-zen-stream3 streamer-irq[1436]: unlink: /data/ifi-k2-mode Dec 12 11:39:20 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 12 11:39:20 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 12 11:39:20 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 12 11:39:22 ifi-zen-stream3 volumiologrotate[482]: ls: cannot access '/var/log/samba/log.wb-ZEN': No such file or directory Dec 12 11:39:23 ifi-zen-stream3 volumiologrotate[482]: ls: cannot access 'STREAM3': No such file or directory Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: ++++--++++ pause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::servicePause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand pause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand pause took 3 milliseconds Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 3 milliseconds Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 3 milliseconds Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16239,"duration":182,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2193 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","trackType":"flac"} Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState stateService pause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState currentStatus pause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 44ms Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16239,"duration":182,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2193 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","trackType":"flac"} Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState stateService pause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::syncState currentStatus pause Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 12 11:39:30 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 55ms Dec 12 11:39:38 ifi-zen-stream3 kernel: usb 4-1.3: USB disconnect, device number 5 Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: ---------------------------- USB Audio Device Detached Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::Close All Modals sent Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Preparing to save Alsa Options, stopping services first Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPause Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pause Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Saving Audio Output to: {"disallowPush":false,"output_device":{"value":"0,0","label":"SPDIF"}} Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , forceDoP Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Found match in Cards Database: setting mixer for card SPDIF Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Ignoring Mixers Options Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Device 0,0 does not have any Mixer Control Available Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Restoring Previous Volume level: 100 false false Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Updating Volume Controller Parameters: Device: 0,0 Name: SPDIF Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Preparing to generate the ALSA configuration file Dec 12 11:39:38 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: wizard , refreshWizarOutputDevices Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Asound.conf file written Dec 12 11:39:38 ifi-zen-stream3 sudo[4924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 12 11:39:38 ifi-zen-stream3 sudo[4924]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:38 ifi-zen-stream3 sudo[4924]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:38 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 12 11:39:38 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: No state is present for card rkifispdif Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: Found hardware: "rk-ifi-spdif" "" "" "" "" Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: Hardware is initialized using a generic method Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: No state is present for card rkifispdif Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Tidal connect: Output device has changed Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Output device has changed, restarting MPD Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Qobuz connect: Output device has changed Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Roon Ready: Output device has changed Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Spotify: Output device has changed Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Tidal connect: Output device has changed Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Output device has changed, restarting MPD Dec 12 11:39:38 ifi-zen-stream3 sudo[4937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 11:39:38 ifi-zen-stream3 sudo[4937]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Qobuz connect: Output device has changed Dec 12 11:39:38 ifi-zen-stream3 sudo[4937]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Roon Ready: Output device has changed Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: Spotify: Output device has changed Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: MPD Permissions set Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 sudo[4939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 11:39:38 ifi-zen-stream3 sudo[4939]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:38 ifi-zen-stream3 sudo[4939]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 11:39:38 ifi-zen-stream3 volumio[1437]: info: MPD Permissions set Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: Starting RoonReady Sync Dec 12 11:39:39 ifi-zen-stream3 sudo[4964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 12 11:39:39 ifi-zen-stream3 sudo[4964]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:39 ifi-zen-stream3 sudo[4965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 12 11:39:39 ifi-zen-stream3 sudo[4965]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: Starting RoonReady Sync Dec 12 11:39:39 ifi-zen-stream3 node[476]: Closing connection with the client qobuz-connect Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Dec 12 11:39:39 ifi-zen-stream3 node[461]: ConvSwitch: Drop client qobuz-connect Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:39 ifi-zen-stream3 sudo[4969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Dec 12 11:39:39 ifi-zen-stream3 sudo[4969]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Dec 12 11:39:39 ifi-zen-stream3 sudo[4971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 12 11:39:39 ifi-zen-stream3 sudo[4971]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:39 ifi-zen-stream3 sudo[4964]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:39 ifi-zen-stream3 node[476]: Closing connection with the client roon-ready Dec 12 11:39:39 ifi-zen-stream3 node[461]: ConvSwitch: Drop client roon-ready Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Roon Ready Service... Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Roon Ready Service. Dec 12 11:39:39 ifi-zen-stream3 sudo[4969]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:39 ifi-zen-stream3 systemd[1]: Started iFi Streamer Roon Ready Service. Dec 12 11:39:39 ifi-zen-stream3 sudo[4971]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:39 ifi-zen-stream3 sudo[4965]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: RoonReady Started Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: RoonReady Started Dec 12 11:39:39 ifi-zen-stream3 play[4975]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Dec 12 11:39:39 ifi-zen-stream3 node[461]: ConvSwitch: Add client roon-ready Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: ConvSwitch: Get message: request:pause Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::updateTrackBlock Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrackBlock Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stPlaybackTimer Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::serviceStop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::serviceStop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::stop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand stop took 13 milliseconds Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 2 milliseconds Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: ---------------------------- MPD announces state update: player Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::getState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand status Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand status took 7 milliseconds Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioGetState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","trackType":"flac"} Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 42ms Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: Roon Ready playback start Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: Roon Ready metadata receiver is on Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: ControllerMpd::parseTrackInfo Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: ControllerMpd::pushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::servicePushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: CorePlayQueue::getTrack 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac","trackType":"flac"} Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: verbose: CURRENT POSITION 3 Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 12 11:39:39 ifi-zen-stream3 volumio[1437]: info: ------------------------------ 67ms Dec 12 11:39:40 ifi-zen-stream3 sudo[5008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 12 11:39:40 ifi-zen-stream3 sudo[5008]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 12 11:39:40 ifi-zen-stream3 node[476]: Closing connection with the client tidal-connect Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 12 11:39:40 ifi-zen-stream3 node[461]: ConvSwitch: Drop client tidal Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 12 11:39:40 ifi-zen-stream3 sudo[5008]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 12 11:39:40 ifi-zen-stream3 volumio[1437]: info: Tidal Connect Service Started Dec 12 11:39:40 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:40 ifi-zen-stream3 sudo[5014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 12 11:39:40 ifi-zen-stream3 sudo[5014]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:40 ifi-zen-stream3 node[476]: Closing connection with the client tidal-connect Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 12 11:39:40 ifi-zen-stream3 sudo[5014]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:40 ifi-zen-stream3 volumio[1437]: info: Tidal Connect Service Started Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 12 11:39:40 ifi-zen-stream3 sudo[5022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 12 11:39:40 ifi-zen-stream3 sudo[5022]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:40 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Started iFi Streamer Qobuz Connect Service. Dec 12 11:39:40 ifi-zen-stream3 sudo[5022]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:40 ifi-zen-stream3 volumio[1437]: info: Qobuz Connect Service Started Dec 12 11:39:40 ifi-zen-stream3 sudo[5032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 12 11:39:40 ifi-zen-stream3 sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:40 ifi-zen-stream3 sudo[5032]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:40 ifi-zen-stream3 volumio[1437]: info: Qobuz Connect Service Started Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.583 [5053.5053] DEBUG SampleApp: Starting sample application Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.585 [5053.5053] DEBUG SampleApp: Device Name: ZEN Stream3 Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.586 [5053.5053] DEBUG SampleApp: Manufacturer: Sample manufacturer Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.586 [5053.5053] DEBUG SampleApp: Model: iFi Streamer Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.587 [5053.5053] DEBUG SampleApp: Serial Number: ifi:32:d8:07:42:e3:7c Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.587 [5053.5053] DEBUG SampleApp: Audio output device: volumio Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.588 [5053.5053] DEBUG SampleApp: Volume control device: default Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.588 [5053.5053] DEBUG SampleApp: Volume control element: Master Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.589 [5053.5053] DEBUG SampleApp: Local config server port: 8000 Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.593 [5053.5053] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.594 [5053.5053] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Dec 12 11:39:40 ifi-zen-stream3 play[5031]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:40 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:40 ifi-zen-stream3 play[5031]: [Info] MetaClient: MetaClientInit Dec 12 11:39:40 ifi-zen-stream3 play[5031]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 12 11:39:40 ifi-zen-stream3 node[461]: ConvSwitch: Add client qobuz-connect Dec 12 11:39:40 ifi-zen-stream3 play[5031]: [Info] ConvSwitch: ConvSwitchInit Dec 12 11:39:40 ifi-zen-stream3 sudo[5057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 12 11:39:40 ifi-zen-stream3 sudo[5057]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.701 [5053.5053] DEBUG ActiveStateManager: [0xab3f9098]: Creating new Active State Manager Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.701 [5053.5053] DEBUG NetworkActivityTracker: [0xab3f9108]: Creating new Network Activity Tracker Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG EndpointManager: [0xab3fa080]: Creating Endpoint Manager Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG PlaybackSessionManager: [0xab3fa470]: Creating Playback Session Manager Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG BackoffTimer: [0xab3fa5e8]: Creating Backoff Timer Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG MediaEngine: [0xab3fa708]: Creating new Media Engine Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG AudioRenderer: [0xab3fa928]: Creating Audio Renderer Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] TRACE MediaEngineCommandSequencer: [0xab3faa80]: Creating Media Engine Command Sequencer Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG PlaybackControlsManager: [0xab3faae0]: Creating new Playback Controls Manager Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG VolumeManager: [0xab3fab70]: Creating new Volume Manager Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG ProtocolHandler: [0xab3fae48]: Creating Protocol Handler Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG MessageReceiver: [0xab3faee8]: Creating Message Receiver Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG RendererActivationController: [0xab3fb060]: Creating new Renderer Activation Controller Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] TRACE RendererActivationCommandSequencer: [0xab3fb108]: Creating Renderer Activation Command Sequencer Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG ReportingManager: [0xab3fb218]: Creating new Reporting Manager Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG StreamingEventReporter: [0xab3fb260]: Creating Streaming Event Reporter Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG BackoffTimer: [0xab3fb340]: Creating Backoff Timer Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG TokenExpiryController: [0xab3fb450]: Creating Token Expiry Controller Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.702 [5053.5053] DEBUG UserInactivityManager: [0xab3fb4a0]: Creating new User Inactivity Manager Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.703 [5053.5053] INFO VolumeManager: [0xab3fab70]: Setting new playback volume: 100 Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.703 [5053.5053] INFO VolumeManager: [0xab3fab70]: Setting new mute state: 1 Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.703 [5053.5053] DEBUG SampleApp: Qobuz Connect has been initialized Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.703 [5053.5053] TRACE SampleApp: Running event loop Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.703 [5053.5053] INFO QobuzConnect: [0xab3fb540]: Client initialized! Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.703 [5053.5053] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream3, service name: _qobuz-connect._tcp Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.724 [5053.5053] DEBUG SampleApp: Avahi client state changed: RUNNING Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.728 [5053.5053] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopping Music Player Daemon... Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.739 [5053.5053] INFO LocalConfigManager: [0xab3fa450]: Starting Local Configuration server Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.739 [5053.5053] INFO SampleApp: Starting Local configuration server Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.740 [5053.5053] INFO SampleApp: Playback mute state changed: 1 Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.740 [5053.5053] ERROR SampleApp: Unable to set mute state, no ALSA volume element Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.740 [5053.5053] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.740 [5053.5053] INFO SampleApp: Playback volume changed: 100 Dec 12 11:39:40 ifi-zen-stream3 play[5031]: 20251212 11:39:40.741 [5053.5053] ERROR SampleApp: Unable to set volume, no ALSA volume element Dec 12 11:39:40 ifi-zen-stream3 sudo[5060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 12 11:39:40 ifi-zen-stream3 sudo[5060]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 12 11:39:40 ifi-zen-stream3 sudo[5057]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:40 ifi-zen-stream3 sudo[5065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:40 ifi-zen-stream3 sudo[5065]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:40 ifi-zen-stream3 sudo[5060]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 12 11:39:40 ifi-zen-stream3 sudo[5068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 12 11:39:40 ifi-zen-stream3 sudo[5068]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopping mpd.socket. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Failed to listen on mpd.socket. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 12 11:39:40 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 12 11:39:41 ifi-zen-stream3 sudo[5073]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 11:39:41 ifi-zen-stream3 sudo[5073]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:41 ifi-zen-stream3 sudo[5073]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:41 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:41 ifi-zen-stream3 play[5031]: 20251212 11:39:41.717 [5053.5053] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 12 11:39:42 ifi-zen-stream3 sudo[5085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 12 11:39:42 ifi-zen-stream3 sudo[5085]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 12 11:39:42 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:42.279 E esdk select error: 4 -> Interrupted system call Dec 12 11:39:42 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:42.280 I app Exiting with error = 0 Dec 12 11:39:42 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:42.280 I api SpFree() Dec 12 11:39:42 ifi-zen-stream3 play[4475]: 2025-12-12 11:39:42.281 I api SpFree [returned value: 0] Dec 12 11:39:42 ifi-zen-stream3 play[4475]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 12 11:39:42 ifi-zen-stream3 play[4475]: [Info] ConvSwitch: ConvSwitchInit Dec 12 11:39:42 ifi-zen-stream3 play[4475]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:42 ifi-zen-stream3 play[4475]: [Info] MetaClient: MetaClientInit Dec 12 11:39:42 ifi-zen-stream3 play[4475]: [Info] ConvSwitch: ConvSwitchExit Dec 12 11:39:42 ifi-zen-stream3 play[4475]: [Info] MetaClient: MetaClientExit Dec 12 11:39:42 ifi-zen-stream3 node[461]: ConvSwitch: Drop client spotify Dec 12 11:39:42 ifi-zen-stream3 node[476]: Closing connection with the client spotify Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::getcurrentVolume Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioRetrievevolume Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 12 11:39:42 ifi-zen-stream3 sudo[5085]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:42 ifi-zen-stream3 sudo[5088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: Spotify Connect Service Started Dec 12 11:39:42 ifi-zen-stream3 sudo[5088]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 12 11:39:42 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 12 11:39:42 ifi-zen-stream3 sudo[5088]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: Spotify Connect Service Started Dec 12 11:39:42 ifi-zen-stream3 play[5093]: output: volumio mixer: hw:0 Dec 12 11:39:42 ifi-zen-stream3 node[461]: ConvSwitch: Add client spotify Dec 12 11:39:42 ifi-zen-stream3 node[476]: A new connection has been established. Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.625 I api SpRegisterDebugCallbacks [returned value: 0] Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.625 I api SpInit(0xffba8c00, API v66) Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.625 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.626 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 Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.626 I esdk Successfully registered dns callback: dns_lookup_callback Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.626 I download Using 128 kB for cache buffer Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.626 I download Using 621 kB for compressed audio buffer Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.626 I esdk Successfully registered error callback Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.638 I esdk Creating IPv4 socket (domain 2) Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.638 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.638 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.638 I esdk Creating IPv4 socket (domain 2) Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.639 I api SpInit [returned value: 0] Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.639 I api SpRegisterConnectionCallbacks(0xffba8be8, (nil)) Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.639 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.639 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.639 I api SpRegisterPlaybackCallbacks(0xffba8bd0, (nil)) Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.639 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.639 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.645 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 12 11:39:42 ifi-zen-stream3 play[5093]: 2025-12-12 11:39:42.646 I app Initialized! Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: VolumeController::SetAlsaVolume100 Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::pushState Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioPushState Dec 12 11:39:42 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 11:39:42 ifi-zen-stream3 node[461]: ConvSwitch: Add client tidal Dec 12 11:39:42 ifi-zen-stream3 tidal_connect_application[5106]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 12 11:39:42 ifi-zen-stream3 tidal_connect_application[5106]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 12 11:39:42 ifi-zen-stream3 tidal_connect_application[5106]: *** WARNING *** For more information see Dec 12 11:39:43 ifi-zen-stream3 ifi_metadata[5023]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 12 11:39:43 ifi-zen-stream3 ifi_metadata[5023]: [Info] MetaClient: MetaClientInit Dec 12 11:39:43 ifi-zen-stream3 ifi_metadata[5023]: Connected Dec 12 11:39:43 ifi-zen-stream3 mpd[5077]: Dec 12 11:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 11:39:43 ifi-zen-stream3 systemd[1]: Started Music Player Daemon. Dec 12 11:39:43 ifi-zen-stream3 sudo[5065]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:43 ifi-zen-stream3 sudo[5068]: pam_unix(sudo:session): session closed for user root Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: info: Restarted MPD. Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: Job for mpd.socket failed. Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: error: MPD error: The expression evaluated to a falsy value: Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: assert.ok(self.idling) Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: error: The expression evaluated to a falsy value: Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: assert.ok(self.idling) Dec 12 11:39:43 ifi-zen-stream3 volumio[1437]: error: updateQueue error: null Dec 12 11:39:43 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 12 11:39:44 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 12 11:39:44 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::serviceStop Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::serviceStop Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioStop Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::stop Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 11:39:44 ifi-zen-stream3 volumio[1437]: info: Roon Ready Stop Dec 12 11:39:51 ifi-zen-stream3 volumio[1437]: info: ++++--++++ volatilePlay: null Dec 12 11:39:51 ifi-zen-stream3 volumio[1437]: info: CoreCommandRouter::volumioVolatilePlay Dec 12 11:39:51 ifi-zen-stream3 volumio[1437]: info: CoreStateMachine::volatilePlay Dec 12 11:39:51 ifi-zen-stream3 volumio[1437]: info: WARNING: Received volatile play command but volumio is not on volatile state Dec 12 11:40:09 ifi-zen-stream3 volumio[1437]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: Error: connect ETIMEDOUT 104.244.46.17:443 Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: errno: -110, Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: code: 'ETIMEDOUT', Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: syscall: 'connect', Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: address: '104.244.46.17', Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: port: 443 Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: } Dec 12 11:40:10 ifi-zen-stream3 volumio[1437]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 11:40:10 ifi-zen-stream3 sudo[5383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-12 11:39 Dec 12 11:40:10 ifi-zen-stream3 sudo[5383]: 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="45aad196ab181636cec04ab79f52572f8ba3c549" VOLUMIO_FE_VERSION="8a7c1663e5becc06293ef92fd46324fc2cb9ce6e" VOLUMIO_FE3_VERSION="c5cad6acce062070373bc18affd0d2a1a9956a16" VOLUMIO_BE_VERSION="0fad662d4c47d7729fc677cd069fd5e675bebf9c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Nov 15 15:45:37 CST 2025" VOLUMIO_VERSION="0.00.7" VOLUMIO_HARDWARE="zen-stream3" VOLUMIO_DEVICENAME="ZEN Stream3" VOLUMIO_HASH="3350ed41a04d50b70f39bebb8e49f746"