-- Logs begin at Sat 2025-11-15 07:41:13 UTC, end at Thu 2025-12-11 09:03:00 UTC. -- Dec 11 09:02:00 ifi-zen-stream3 sudo[4118]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:00 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:00 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 11 09:02:00 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 11 09:02:00 ifi-zen-stream3 play[4066]: 20251211 09:02:00.385 [4084.4084] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::getcurrentVolume Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:01 ifi-zen-stream3 sudo[4135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 11 09:02:01 ifi-zen-stream3 sudo[4135]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:01 ifi-zen-stream3 play[3314]: 2025-12-11 09:02:01.142 E esdk select error: 4 -> Interrupted system call Dec 11 09:02:01 ifi-zen-stream3 play[3314]: 2025-12-11 09:02:01.143 I app Exiting with error = 0 Dec 11 09:02:01 ifi-zen-stream3 play[3314]: 2025-12-11 09:02:01.143 I api SpFree() Dec 11 09:02:01 ifi-zen-stream3 play[3314]: 2025-12-11 09:02:01.144 I api SpFree [returned value: 0] Dec 11 09:02:01 ifi-zen-stream3 play[3314]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 11 09:02:01 ifi-zen-stream3 play[3314]: [Info] ConvSwitch: ConvSwitchInit Dec 11 09:02:01 ifi-zen-stream3 play[3314]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 11 09:02:01 ifi-zen-stream3 play[3314]: [Info] MetaClient: MetaClientInit Dec 11 09:02:01 ifi-zen-stream3 play[3314]: [Info] ConvSwitch: ConvSwitchExit Dec 11 09:02:01 ifi-zen-stream3 play[3314]: [Info] MetaClient: MetaClientExit Dec 11 09:02:01 ifi-zen-stream3 node[471]: Closing connection with the client spotify Dec 11 09:02:01 ifi-zen-stream3 node[484]: ConvSwitch: Drop client spotify Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 11 09:02:01 ifi-zen-stream3 sudo[4135]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:01 ifi-zen-stream3 sudo[4138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Spotify Connect Service Started Dec 11 09:02:01 ifi-zen-stream3 sudo[4138]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 11 09:02:01 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 11 09:02:01 ifi-zen-stream3 sudo[4138]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Spotify Connect Service Started Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: new full-speed USB device number 5 using xhci-hcd Dec 11 09:02:01 ifi-zen-stream3 play[4150]: output: volumio mixer: hw:0 Dec 11 09:02:01 ifi-zen-stream3 node[484]: ConvSwitch: Add client spotify Dec 11 09:02:01 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: not running at top speed; connect to a high speed hub Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: New USB device found, idVendor=20b1, idProduct=0010 Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: New USB device strings: Mfr=1, Product=8, SerialNumber=2 Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: Product: ZEN DAC 3 (UCA1) Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: Manufacturer: iFi (by AMR) Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: SerialNumber: 005A Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.484 I api SpRegisterDebugCallbacks [returned value: 0] Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.484 I api SpInit(0xff9288f0, API v66) Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.484 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.485 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 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.485 I esdk Successfully registered dns callback: dns_lookup_callback Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.485 I download Using 128 kB for cache buffer Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.485 I download Using 621 kB for compressed audio buffer Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.485 I esdk Successfully registered error callback Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: VolumeController::SetAlsaVolume100 Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.497 I esdk Creating IPv4 socket (domain 2) Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.497 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.497 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.497 I esdk Creating IPv4 socket (domain 2) Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.498 I api SpInit [returned value: 0] Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.498 I api SpRegisterConnectionCallbacks(0xff9288d8, (nil)) Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.498 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.498 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.498 I api SpRegisterPlaybackCallbacks(0xff9288c0, (nil)) Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.498 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.498 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.504 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 11 09:02:01 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:01.504 I app Initialized! Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: Warning! Unlikely big volume range (=32512), cval->res is probably wrong. Dec 11 09:02:01 ifi-zen-stream3 kernel: usb 4-1.3: [10] FU [ZEN DAC 3 (UCA1) Playback Volume] ch = 1, val = -32512/0/1 Dec 11 09:02:01 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 11 09:02:01 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 11 09:02:01 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 11 09:02:01 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 11 09:02:01 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 11 09:02:01 ifi-zen-stream3 systemd-udevd[4170]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: ---------------------------- USB Audio Device Attached Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::Close All Modals sent Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Preparing to save Alsa Options, stopping services first Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Saving Audio Output to: {"disallowPush":false,"output_device":{"value":"5","label":"ZEN DAC 3 (UCA1)"}} Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , forceDoP Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: ZEN DAC 3 (UCA1): Does not support DSD Native. Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Ignoring Mixers Options Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Device 5 does not have any Mixer Control Available Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Restoring Previous Volume level: 100 false false Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Updating Volume Controller Parameters: Device: 5 Name: ZEN DAC 3 (UCA1) Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:01 ifi-zen-stream3 volumio[1387]: info: Preparing to generate the ALSA configuration file Dec 11 09:02:01 ifi-zen-stream3 node[484]: ConvSwitch: Add client tidal Dec 11 09:02:02 ifi-zen-stream3 tidal_connect_application[4172]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 11 09:02:02 ifi-zen-stream3 tidal_connect_application[4172]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 09:02:02 ifi-zen-stream3 tidal_connect_application[4172]: *** WARNING *** For more information see Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , refreshWizarOutputDevices Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Asound.conf file written Dec 11 09:02:02 ifi-zen-stream3 ifi_metadata[4049]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 11 09:02:02 ifi-zen-stream3 ifi_metadata[4049]: [Info] MetaClient: MetaClientInit Dec 11 09:02:02 ifi-zen-stream3 ifi_metadata[4049]: Connected Dec 11 09:02:02 ifi-zen-stream3 sudo[4222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 09:02:02 ifi-zen-stream3 sudo[4222]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:02 ifi-zen-stream3 sudo[4222]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: No state is present for card rkifispdif Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: Found hardware: "rk-ifi-spdif" "" "" "" "" Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: Hardware is initialized using a generic method Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: No state is present for card rkifispdif Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: No state is present for card Z3UCA1 Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:0010" "" "" Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: Hardware is initialized using a generic method Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: No state is present for card Z3UCA1 Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Tidal connect: Output device has changed Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Output device has changed, restarting MPD Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Qobuz connect: Output device has changed Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Roon Ready: Output device has changed Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Spotify: Output device has changed Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Tidal connect: Output device has changed Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Output device has changed, restarting MPD Dec 11 09:02:02 ifi-zen-stream3 sudo[4227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 09:02:02 ifi-zen-stream3 sudo[4227]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:02 ifi-zen-stream3 sudo[4227]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Qobuz connect: Output device has changed Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Roon Ready: Output device has changed Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Spotify: Output device has changed Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: MPD Permissions set Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 sudo[4230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 09:02:02 ifi-zen-stream3 sudo[4230]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 sudo[4230]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: MPD Permissions set Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Starting RoonReady Sync Dec 11 09:02:02 ifi-zen-stream3 sudo[4246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 11 09:02:02 ifi-zen-stream3 sudo[4246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:02 ifi-zen-stream3 sudo[4248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 11 09:02:02 ifi-zen-stream3 sudo[4248]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:02 ifi-zen-stream3 volumio[1387]: info: Starting RoonReady Sync Dec 11 09:02:02 ifi-zen-stream3 node[484]: ConvSwitch: Drop client qobuz-connect Dec 11 09:02:02 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Dec 11 09:02:02 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:02 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Dec 11 09:02:02 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Dec 11 09:02:02 ifi-zen-stream3 sudo[4254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 11 09:02:02 ifi-zen-stream3 sudo[4254]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:02 ifi-zen-stream3 node[471]: Closing connection with the client qobuz-connect Dec 11 09:02:02 ifi-zen-stream3 sudo[4257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 11 09:02:02 ifi-zen-stream3 sudo[4257]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:03 ifi-zen-stream3 sudo[4246]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:03 ifi-zen-stream3 node[471]: Closing connection with the client roon-ready Dec 11 09:02:03 ifi-zen-stream3 node[484]: ConvSwitch: Drop client roon-ready Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Roon Ready Service... Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Roon Ready Service. Dec 11 09:02:03 ifi-zen-stream3 sudo[4254]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Started iFi Streamer Roon Ready Service. Dec 11 09:02:03 ifi-zen-stream3 sudo[4248]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:03 ifi-zen-stream3 sudo[4257]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: RoonReady Started Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: RoonReady Started Dec 11 09:02:03 ifi-zen-stream3 play[4261]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Dec 11 09:02:03 ifi-zen-stream3 mpd[4120]: Dec 11 09:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Started Music Player Daemon. Dec 11 09:02:03 ifi-zen-stream3 sudo[4097]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:03 ifi-zen-stream3 sudo[4100]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: Restarted MPD. Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: Restarted MPD. Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: error: MPD error: The expression evaluated to a falsy value: Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: assert.ok(self.idling) Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: error: The expression evaluated to a falsy value: Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: assert.ok(self.idling) Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: error: updateQueue error: null Dec 11 09:02:03 ifi-zen-stream3 node[484]: ConvSwitch: Add client roon-ready Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: ConvSwitch: Get message: request:pause Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::serviceStop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::serviceStop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: Roon Ready Stop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: Roon Ready playback start Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: Roon Ready metadata receiver is on Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:03 ifi-zen-stream3 sudo[4310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 11 09:02:03 ifi-zen-stream3 sudo[4310]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:03 ifi-zen-stream3 node[471]: Closing connection with the client tidal-connect Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:03 ifi-zen-stream3 node[484]: ConvSwitch: Drop client tidal Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 11 09:02:03 ifi-zen-stream3 sudo[4310]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: Tidal Connect Service Started Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 11 09:02:03 ifi-zen-stream3 sudo[4313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 11 09:02:03 ifi-zen-stream3 sudo[4313]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:03 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:03 ifi-zen-stream3 node[471]: Closing connection with the client tidal-connect Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 11 09:02:03 ifi-zen-stream3 sudo[4313]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:03 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 11 09:02:03 ifi-zen-stream3 volumio[1387]: info: Tidal Connect Service Started Dec 11 09:02:03 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:04 ifi-zen-stream3 sudo[4333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 11 09:02:04 ifi-zen-stream3 sudo[4333]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Started iFi Streamer Qobuz Connect Service. Dec 11 09:02:04 ifi-zen-stream3 sudo[4333]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:04 ifi-zen-stream3 volumio[1387]: info: Qobuz Connect Service Started Dec 11 09:02:04 ifi-zen-stream3 sudo[4337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 11 09:02:04 ifi-zen-stream3 sudo[4337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:04 ifi-zen-stream3 sudo[4337]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:04 ifi-zen-stream3 volumio[1387]: info: Qobuz Connect Service Started Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Starting sample application Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Device Name: ZEN Stream3 Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Manufacturer: Sample manufacturer Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Model: iFi Streamer Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Serial Number: ifi:f2:e7:c1:18:db:6a Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Audio output device: volumio Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Volume control device: default Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Volume control element: Master Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.268 [4355.4355] DEBUG SampleApp: Local config server port: 8000 Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.273 [4355.4355] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.273 [4355.4355] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Dec 11 09:02:04 ifi-zen-stream3 play[4335]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 11 09:02:04 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:04 ifi-zen-stream3 play[4335]: [Info] MetaClient: MetaClientInit Dec 11 09:02:04 ifi-zen-stream3 play[4335]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 11 09:02:04 ifi-zen-stream3 node[484]: ConvSwitch: Add client qobuz-connect Dec 11 09:02:04 ifi-zen-stream3 sudo[4358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 11 09:02:04 ifi-zen-stream3 sudo[4358]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 11 09:02:04 ifi-zen-stream3 play[4335]: [Info] ConvSwitch: ConvSwitchInit Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.380 [4355.4355] DEBUG ActiveStateManager: [0xab3f5030]: Creating new Active State Manager Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.381 [4355.4355] DEBUG NetworkActivityTracker: [0xab3f50a0]: Creating new Network Activity Tracker Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.382 [4355.4355] DEBUG EndpointManager: [0xab3f6018]: Creating Endpoint Manager Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.382 [4355.4355] DEBUG PlaybackSessionManager: [0xab3f6408]: Creating Playback Session Manager Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.382 [4355.4355] DEBUG BackoffTimer: [0xab3f6580]: Creating Backoff Timer Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.382 [4355.4355] DEBUG MediaEngine: [0xab3f66a0]: Creating new Media Engine Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG AudioRenderer: [0xab3f68c0]: Creating Audio Renderer Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Stopping Music Player Daemon... Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] TRACE MediaEngineCommandSequencer: [0xab3f6a18]: Creating Media Engine Command Sequencer Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG PlaybackControlsManager: [0xab3f6a78]: Creating new Playback Controls Manager Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG VolumeManager: [0xab3f6b08]: Creating new Volume Manager Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG ProtocolHandler: [0xab3f6de0]: Creating Protocol Handler Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG MessageReceiver: [0xab3f6e80]: Creating Message Receiver Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG RendererActivationController: [0xab3f6ff8]: Creating new Renderer Activation Controller Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] TRACE RendererActivationCommandSequencer: [0xab3f70a0]: Creating Renderer Activation Command Sequencer Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG ReportingManager: [0xab3f71b0]: Creating new Reporting Manager Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG StreamingEventReporter: [0xab3f71f8]: Creating Streaming Event Reporter Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG BackoffTimer: [0xab3f72d8]: Creating Backoff Timer Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG TokenExpiryController: [0xab3f73e8]: Creating Token Expiry Controller Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG UserInactivityManager: [0xab3f7438]: Creating new User Inactivity Manager Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] INFO VolumeManager: [0xab3f6b08]: Setting new playback volume: 100 Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] INFO VolumeManager: [0xab3f6b08]: Setting new mute state: 1 Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] DEBUG SampleApp: Qobuz Connect has been initialized Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] TRACE SampleApp: Running event loop Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] INFO QobuzConnect: [0xab3f74d8]: Client initialized! Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.383 [4355.4355] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream3, service name: _qobuz-connect._tcp Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.398 [4355.4355] DEBUG SampleApp: Avahi client state changed: RUNNING Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.402 [4355.4355] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Dec 11 09:02:04 ifi-zen-stream3 sudo[4362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 11 09:02:04 ifi-zen-stream3 sudo[4362]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.410 [4355.4355] INFO LocalConfigManager: [0xab3f63e8]: Starting Local Configuration server Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.410 [4355.4355] INFO SampleApp: Starting Local configuration server Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.412 [4355.4355] INFO SampleApp: Playback mute state changed: 1 Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.412 [4355.4355] ERROR SampleApp: Unable to set mute state, no ALSA volume element Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.412 [4355.4355] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.419 [4355.4355] INFO SampleApp: Playback volume changed: 100 Dec 11 09:02:04 ifi-zen-stream3 play[4335]: 20251211 09:02:04.420 [4355.4355] ERROR SampleApp: Unable to set volume, no ALSA volume element Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 11 09:02:04 ifi-zen-stream3 sudo[4358]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:04 ifi-zen-stream3 sudo[4367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 11 09:02:04 ifi-zen-stream3 sudo[4367]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:04 ifi-zen-stream3 sudo[4362]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 11 09:02:04 ifi-zen-stream3 sudo[4370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 11 09:02:04 ifi-zen-stream3 sudo[4370]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Stopping mpd.socket. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Failed to listen on mpd.socket. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 11 09:02:04 ifi-zen-stream3 sudo[4373]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 09:02:04 ifi-zen-stream3 sudo[4373]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:04 ifi-zen-stream3 sudo[4373]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:04 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 11 09:02:04 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:05 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 11 09:02:05 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 11 09:02:05 ifi-zen-stream3 play[4335]: 20251211 09:02:05.177 [4355.4355] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 11 09:02:05 ifi-zen-stream3 sudo[4408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 11 09:02:05 ifi-zen-stream3 sudo[4408]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:05 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 09:02:05 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::getcurrentVolume Dec 11 09:02:05 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 09:02:05 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 11 09:02:05 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:05 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:05 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:05 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:05.916 E esdk select error: 4 -> Interrupted system call Dec 11 09:02:05 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:05.918 I app Exiting with error = 0 Dec 11 09:02:05 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:05.918 I api SpFree() Dec 11 09:02:05 ifi-zen-stream3 play[4150]: 2025-12-11 09:02:05.919 I api SpFree [returned value: 0] Dec 11 09:02:05 ifi-zen-stream3 play[4150]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 11 09:02:05 ifi-zen-stream3 play[4150]: [Info] ConvSwitch: ConvSwitchInit Dec 11 09:02:05 ifi-zen-stream3 play[4150]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 11 09:02:05 ifi-zen-stream3 play[4150]: [Info] MetaClient: MetaClientInit Dec 11 09:02:05 ifi-zen-stream3 play[4150]: [Info] ConvSwitch: ConvSwitchExit Dec 11 09:02:05 ifi-zen-stream3 play[4150]: [Info] MetaClient: MetaClientExit Dec 11 09:02:05 ifi-zen-stream3 node[484]: ConvSwitch: Drop client spotify Dec 11 09:02:05 ifi-zen-stream3 node[471]: Closing connection with the client spotify Dec 11 09:02:05 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 11 09:02:05 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:05 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 11 09:02:05 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 11 09:02:05 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 11 09:02:05 ifi-zen-stream3 sudo[4408]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:05 ifi-zen-stream3 volumio[1387]: info: Spotify Connect Service Started Dec 11 09:02:06 ifi-zen-stream3 sudo[4412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 11 09:02:06 ifi-zen-stream3 sudo[4412]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:06 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 11 09:02:06 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:06 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 11 09:02:06 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 11 09:02:06 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 11 09:02:06 ifi-zen-stream3 sudo[4412]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:06 ifi-zen-stream3 volumio[1387]: info: Spotify Connect Service Started Dec 11 09:02:06 ifi-zen-stream3 play[4420]: output: volumio mixer: hw:5 Dec 11 09:02:06 ifi-zen-stream3 node[484]: ConvSwitch: Add client spotify Dec 11 09:02:06 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.291 I api SpRegisterDebugCallbacks [returned value: 0] Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.292 I api SpInit(0xffd1a150, API v66) Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.292 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.292 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 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.293 I esdk Successfully registered dns callback: dns_lookup_callback Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.293 I download Using 128 kB for cache buffer Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.293 I download Using 621 kB for compressed audio buffer Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.293 I esdk Successfully registered error callback Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.305 I esdk Creating IPv4 socket (domain 2) Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.305 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.305 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.305 I esdk Creating IPv4 socket (domain 2) Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.305 I api SpInit [returned value: 0] Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.306 I api SpRegisterConnectionCallbacks(0xffd1a138, (nil)) Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.306 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.306 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.306 I api SpRegisterPlaybackCallbacks(0xffd1a120, (nil)) Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.306 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.306 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.312 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 11 09:02:06 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:06.312 I app Initialized! Dec 11 09:02:06 ifi-zen-stream3 volumio[1387]: info: VolumeController::SetAlsaVolume100 Dec 11 09:02:06 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 11 09:02:06 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:06 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:06 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:06 ifi-zen-stream3 node[484]: ConvSwitch: Add client tidal Dec 11 09:02:06 ifi-zen-stream3 tidal_connect_application[4430]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 11 09:02:06 ifi-zen-stream3 tidal_connect_application[4430]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 09:02:06 ifi-zen-stream3 tidal_connect_application[4430]: *** WARNING *** For more information see Dec 11 09:02:06 ifi-zen-stream3 ifi_metadata[4323]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 11 09:02:06 ifi-zen-stream3 ifi_metadata[4323]: [Info] MetaClient: MetaClientInit Dec 11 09:02:06 ifi-zen-stream3 ifi_metadata[4323]: Connected Dec 11 09:02:06 ifi-zen-stream3 kernel: usb 5-1.1: new SuperSpeed USB device number 4 using xhci-hcd Dec 11 09:02:06 ifi-zen-stream3 kernel: usb 5-1.1: New USB device found, idVendor=04e8, idProduct=6300 Dec 11 09:02:06 ifi-zen-stream3 kernel: usb 5-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 11 09:02:06 ifi-zen-stream3 kernel: usb 5-1.1: Product: Flash Drive Dec 11 09:02:06 ifi-zen-stream3 kernel: usb 5-1.1: Manufacturer: Samsung Dec 11 09:02:06 ifi-zen-stream3 kernel: usb 5-1.1: SerialNumber: 0362025080001663 Dec 11 09:02:06 ifi-zen-stream3 kernel: usb-storage 5-1.1:1.0: USB Mass Storage device detected Dec 11 09:02:06 ifi-zen-stream3 kernel: scsi host1: usb-storage 5-1.1:1.0 Dec 11 09:02:06 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 11 09:02:06 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 11 09:02:06 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 11 09:02:06 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 11 09:02:06 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 11 09:02:06 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 11 09:02:06 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 11 09:02:07 ifi-zen-stream3 mpd[4375]: Dec 11 09:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 09:02:07 ifi-zen-stream3 systemd[1]: Started Music Player Daemon. Dec 11 09:02:07 ifi-zen-stream3 sudo[4370]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:07 ifi-zen-stream3 sudo[4367]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: Job for mpd.socket failed. Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: info: Restarted MPD. Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: error: MPD error: The expression evaluated to a falsy value: Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: assert.ok(self.idling) Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: error: The expression evaluated to a falsy value: Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: assert.ok(self.idling) Dec 11 09:02:07 ifi-zen-stream3 volumio[1387]: error: updateQueue error: null Dec 11 09:02:07 ifi-zen-stream3 kernel: scsi 1:0:0:0: Direct-Access Samsung Flash Drive 1100 PQ: 0 ANSI: 6 Dec 11 09:02:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Dec 11 09:02:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply from device tree Dec 11 09:02:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Dec 11 09:02:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Dec 11 09:02:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply from device tree Dec 11 09:02:07 ifi-zen-stream3 kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Dec 11 09:02:07 ifi-zen-stream3 kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Dec 11 09:02:07 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] 125313283 512-byte logical blocks: (64.2 GB/59.8 GiB) Dec 11 09:02:07 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] Write Protect is off Dec 11 09:02:07 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] Mode Sense: 43 00 00 00 Dec 11 09:02:07 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Dec 11 09:02:07 ifi-zen-stream3 kernel: sda: sda1 Dec 11 09:02:07 ifi-zen-stream3 kernel: sd 1:0:0:0: [sda] Attached SCSI removable disk Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Mounting Device Samsung_USB Dec 11 09:02:08 ifi-zen-stream3 sudo[4502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Samsung_USB -o noatime Dec 11 09:02:08 ifi-zen-stream3 sudo[4502]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:08 ifi-zen-stream3 mount.exfat[4504]: volume was not unmounted cleanly Dec 11 09:02:08 ifi-zen-stream3 sudo[4502]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: WARN: volume was not unmounted cleanly. Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::serviceStop Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::serviceStop Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Roon Ready Stop Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Scanning new location : "USB/Samsung_USB" Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: update Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: update Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 19 milliseconds Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 13 milliseconds Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 11 milliseconds Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 7 milliseconds Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Command Router : Notfying DB Updatetrue Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Command Router : Notfying DB Updatetrue Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: 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 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: 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 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 152ms Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 144ms Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: update Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: update Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 14 milliseconds Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 12 milliseconds Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 11 milliseconds Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 8 milliseconds Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Command Router : Notfying DB Updatefalse Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: Command Router : Notfying DB Updatefalse Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: 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 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: 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 11 09:02:08 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 4 Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 130ms Dec 11 09:02:08 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 125ms Dec 11 09:02:10 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getMenuItems Dec 11 09:02:10 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getMenuItems Dec 11 09:02:11 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Dec 11 09:02:11 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 09:02:12 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 09:02:12 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 11 09:02:12 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 11 09:02:12 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 11 09:02:14 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getMenuItems Dec 11 09:02:14 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getMenuItems Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 09:02:16 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ++++--++++ playItemsList: {"item":{"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=980_1765443630305&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=980_1765443630304&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=980_1765443630305&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=980_1765443630305&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=980_1765443630305&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=980_1765443630305&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=980_1765443630305&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=980_1765443630305&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=980_1765443630306&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=980_1765443630306&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=980_1765443630306&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"}],"index":1} Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Preload queue cleared Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::ClearQueue Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::clearPlayQueue Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::saveQueue Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::addQueueItems Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::addQueueItems Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Preload queue cleared Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::saveQueue Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::updateTrackBlock Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrackBlock Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ConvSwitch: Sent Play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPlay Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::play index 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::addQueueItems Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::addQueueItems Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Preload queue cleared Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::saveQueue Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::play index undefined Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::updateTrackBlock Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrackBlock Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::startPlaybackTimer Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ConvSwitch: Get message: success Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand stop took 9 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces system playlist update Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Ignoring MPD Status Update Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand clear took 5 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac" Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces system playlist update Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Ignoring MPD Status Update Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: error: updateQueue error: null Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces system playlist update Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Ignoring MPD Status Update Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 14ms Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand add "USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac" took 12 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 11ms Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces system playlist update Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Ignoring MPD Status Update Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 26ms Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand play took 21 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 19ms Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 230 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 236 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"750 Kbps","isStreaming":false,"title":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus stop Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 266ms Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 21 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 4 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":797,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"802 Kbps","isStreaming":false,"title":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 308ms Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 42 milliseconds Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"802 Kbps","isStreaming":false,"title":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"802 Kbps","isStreaming":false,"title":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 151ms Dec 11 09:02:19 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 134ms Dec 11 09:02:20 ifi-zen-stream3 volumio[1387]: info: MetaClient: Sent Meta Dec 11 09:02:20 ifi-zen-stream3 volumio[1387]: info: MetaClient: Get message: success Dec 11 09:02:30 ifi-zen-stream3 streamer-irq[1386]: file inexistence, touch: /data/ifi-k2-mode Dec 11 09:02:30 ifi-zen-stream3 streamer-irq[1386]: had touch: /data/ifi-k2-mode Dec 11 09:02:32 ifi-zen-stream3 streamer-irq[1386]: unlink: /data/ifi-k2-mode Dec 11 09:02:36 ifi-zen-stream3 kernel: usb 4-1.3: USB disconnect, device number 5 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 15 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 24 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand clearerror took 16 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18028,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1543 Kbps","isStreaming":false,"title":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 65ms Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand clearerror took 41 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18028,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1543 Kbps","isStreaming":false,"title":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 94ms Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: ---------------------------- USB Audio Device Detached Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::Close All Modals sent Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Preparing to save Alsa Options, stopping services first Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stPlaybackTimer Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::servicePause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Saving Audio Output to: {"disallowPush":false,"output_device":{"value":"0,0","label":"SPDIF"}} Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , forceDoP Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Found match in Cards Database: setting mixer for card SPDIF Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Ignoring Mixers Options Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Device 0,0 does not have any Mixer Control Available Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Restoring Previous Volume level: 100 false false Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Updating Volume Controller Parameters: Device: 0,0 Name: SPDIF Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Preparing to generate the ALSA configuration file Dec 11 09:02:36 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand pause took 192 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 22 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 18 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand clearerror took 23 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand clearerror took 20 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1543 Kbps","isStreaming":false,"title":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stPlaybackTimer Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1543 Kbps","isStreaming":false,"title":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus pause Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stPlaybackTimer Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 108ms Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 102ms Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:36 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , refreshWizarOutputDevices Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Asound.conf file written Dec 11 09:02:37 ifi-zen-stream3 sudo[4858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 09:02:37 ifi-zen-stream3 sudo[4858]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:37 ifi-zen-stream3 sudo[4858]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: No state is present for card rkifispdif Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: Found hardware: "rk-ifi-spdif" "" "" "" "" Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: Hardware is initialized using a generic method Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: No state is present for card rkifispdif Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Tidal connect: Output device has changed Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Output device has changed, restarting MPD Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Qobuz connect: Output device has changed Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Roon Ready: Output device has changed Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Spotify: Output device has changed Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Tidal connect: Output device has changed Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Output device has changed, restarting MPD Dec 11 09:02:37 ifi-zen-stream3 sudo[4863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 09:02:37 ifi-zen-stream3 sudo[4863]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Qobuz connect: Output device has changed Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Roon Ready: Output device has changed Dec 11 09:02:37 ifi-zen-stream3 sudo[4863]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Spotify: Output device has changed Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: MPD Permissions set Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 sudo[4865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 09:02:37 ifi-zen-stream3 sudo[4865]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 sudo[4865]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: MPD Permissions set Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Starting RoonReady Sync Dec 11 09:02:37 ifi-zen-stream3 sudo[4885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 11 09:02:37 ifi-zen-stream3 sudo[4885]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: Starting RoonReady Sync Dec 11 09:02:37 ifi-zen-stream3 sudo[4886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 11 09:02:37 ifi-zen-stream3 sudo[4886]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:37 ifi-zen-stream3 sudo[4893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 11 09:02:37 ifi-zen-stream3 sudo[4893]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:37 ifi-zen-stream3 node[484]: ConvSwitch: Drop client roon-ready Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Roon Ready Service... Dec 11 09:02:37 ifi-zen-stream3 node[471]: Closing connection with the client roon-ready Dec 11 09:02:37 ifi-zen-stream3 node[471]: Closing connection with the client qobuz-connect Dec 11 09:02:37 ifi-zen-stream3 sudo[4897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 11 09:02:37 ifi-zen-stream3 node[484]: ConvSwitch: Drop client qobuz-connect Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Dec 11 09:02:37 ifi-zen-stream3 sudo[4897]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Roon Ready Service. Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Dec 11 09:02:37 ifi-zen-stream3 sudo[4885]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:37 ifi-zen-stream3 systemd[1]: Started iFi Streamer Roon Ready Service. Dec 11 09:02:37 ifi-zen-stream3 sudo[4886]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:37 ifi-zen-stream3 sudo[4897]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: RoonReady Started Dec 11 09:02:37 ifi-zen-stream3 volumio[1387]: info: RoonReady Started Dec 11 09:02:37 ifi-zen-stream3 play[4900]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Dec 11 09:02:37 ifi-zen-stream3 sudo[4893]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:38 ifi-zen-stream3 node[484]: ConvSwitch: Add client roon-ready Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: ConvSwitch: Get message: request:pause Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::updateTrackBlock Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrackBlock Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stPlaybackTimer Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::serviceStop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::serviceStop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::stop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand stop took 16 milliseconds Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 2 milliseconds Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 6 milliseconds Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: 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":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 44ms Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: Roon Ready playback start Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: Roon Ready metadata receiver is on Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: 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":"(48kHz)Divertimento 48-24.flac","artist":null,"album":null,"uri":"USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac","trackType":"flac"} Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 1 Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 68ms Dec 11 09:02:38 ifi-zen-stream3 sudo[4934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 11 09:02:38 ifi-zen-stream3 sudo[4934]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 11 09:02:38 ifi-zen-stream3 node[471]: Closing connection with the client tidal-connect Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:38 ifi-zen-stream3 node[484]: ConvSwitch: Drop client tidal Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 11 09:02:38 ifi-zen-stream3 sudo[4934]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: Tidal Connect Service Started Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 11 09:02:38 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:38 ifi-zen-stream3 sudo[4938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 11 09:02:38 ifi-zen-stream3 sudo[4938]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 11 09:02:38 ifi-zen-stream3 node[471]: Closing connection with the client tidal-connect Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 11 09:02:38 ifi-zen-stream3 sudo[4938]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: Tidal Connect Service Started Dec 11 09:02:38 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:38 ifi-zen-stream3 sudo[4955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 11 09:02:38 ifi-zen-stream3 sudo[4955]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:38 ifi-zen-stream3 systemd[1]: Started iFi Streamer Qobuz Connect Service. Dec 11 09:02:38 ifi-zen-stream3 sudo[4955]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:38 ifi-zen-stream3 volumio[1387]: info: Qobuz Connect Service Started Dec 11 09:02:38 ifi-zen-stream3 sudo[4963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 11 09:02:38 ifi-zen-stream3 sudo[4963]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:39 ifi-zen-stream3 sudo[4963]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:39 ifi-zen-stream3 volumio[1387]: info: Qobuz Connect Service Started Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Starting sample application Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Device Name: ZEN Stream3 Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Manufacturer: Sample manufacturer Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Model: iFi Streamer Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Serial Number: ifi:f2:e7:c1:18:db:6a Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Audio output device: volumio Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Volume control device: default Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Volume control element: Master Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.128 [4981.4981] DEBUG SampleApp: Local config server port: 8000 Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.133 [4981.4981] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.133 [4981.4981] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Dec 11 09:02:39 ifi-zen-stream3 play[4962]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 11 09:02:39 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:39 ifi-zen-stream3 play[4962]: [Info] MetaClient: MetaClientInit Dec 11 09:02:39 ifi-zen-stream3 play[4962]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 11 09:02:39 ifi-zen-stream3 node[484]: ConvSwitch: Add client qobuz-connect Dec 11 09:02:39 ifi-zen-stream3 sudo[4985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 11 09:02:39 ifi-zen-stream3 sudo[4985]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:39 ifi-zen-stream3 play[4962]: [Info] ConvSwitch: ConvSwitchInit Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.243 [4981.4981] DEBUG ActiveStateManager: [0xab528098]: Creating new Active State Manager Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG NetworkActivityTracker: [0xab528108]: Creating new Network Activity Tracker Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG EndpointManager: [0xab529080]: Creating Endpoint Manager Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG PlaybackSessionManager: [0xab529470]: Creating Playback Session Manager Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG BackoffTimer: [0xab5295e8]: Creating Backoff Timer Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG MediaEngine: [0xab529708]: Creating new Media Engine Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG AudioRenderer: [0xab529928]: Creating Audio Renderer Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] TRACE MediaEngineCommandSequencer: [0xab529a80]: Creating Media Engine Command Sequencer Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG PlaybackControlsManager: [0xab529ae0]: Creating new Playback Controls Manager Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG VolumeManager: [0xab529b70]: Creating new Volume Manager Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG ProtocolHandler: [0xab529e48]: Creating Protocol Handler Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG MessageReceiver: [0xab529ee8]: Creating Message Receiver Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.244 [4981.4981] DEBUG RendererActivationController: [0xab52a060]: Creating new Renderer Activation Controller Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] TRACE RendererActivationCommandSequencer: [0xab52a108]: Creating Renderer Activation Command Sequencer Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] DEBUG ReportingManager: [0xab52a218]: Creating new Reporting Manager Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] DEBUG StreamingEventReporter: [0xab52a260]: Creating Streaming Event Reporter Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] DEBUG BackoffTimer: [0xab52a340]: Creating Backoff Timer Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] DEBUG TokenExpiryController: [0xab52a450]: Creating Token Expiry Controller Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] DEBUG UserInactivityManager: [0xab52a4a0]: Creating new User Inactivity Manager Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] INFO VolumeManager: [0xab529b70]: Setting new playback volume: 100 Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] INFO VolumeManager: [0xab529b70]: Setting new mute state: 1 Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] DEBUG SampleApp: Qobuz Connect has been initialized Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] TRACE SampleApp: Running event loop Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] INFO QobuzConnect: [0xab52a540]: Client initialized! Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.245 [4981.4981] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream3, service name: _qobuz-connect._tcp Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 11 09:02:39 ifi-zen-stream3 sudo[4988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.263 [4981.4981] DEBUG SampleApp: Avahi client state changed: RUNNING Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 11 09:02:39 ifi-zen-stream3 sudo[4988]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Stopping Music Player Daemon... Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.273 [4981.4981] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.281 [4981.4981] INFO LocalConfigManager: [0xab529450]: Starting Local Configuration server Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.281 [4981.4981] INFO SampleApp: Starting Local configuration server Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.282 [4981.4981] INFO SampleApp: Playback mute state changed: 1 Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.282 [4981.4981] ERROR SampleApp: Unable to set mute state, no ALSA volume element Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.282 [4981.4981] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.282 [4981.4981] INFO SampleApp: Playback volume changed: 100 Dec 11 09:02:39 ifi-zen-stream3 play[4962]: 20251211 09:02:39.282 [4981.4981] ERROR SampleApp: Unable to set volume, no ALSA volume element Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 11 09:02:39 ifi-zen-stream3 sudo[4985]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:39 ifi-zen-stream3 sudo[4988]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:39 ifi-zen-stream3 sudo[4993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 11 09:02:39 ifi-zen-stream3 sudo[4993]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:39 ifi-zen-stream3 sudo[4995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 11 09:02:39 ifi-zen-stream3 sudo[4995]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Stopping mpd.socket. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Failed to listen on mpd.socket. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 11 09:02:39 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 11 09:02:39 ifi-zen-stream3 sudo[4999]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 09:02:39 ifi-zen-stream3 sudo[4999]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:39 ifi-zen-stream3 sudo[4999]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:39 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:40 ifi-zen-stream3 play[4962]: 20251211 09:02:40.068 [4981.4981] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::getcurrentVolume Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:40 ifi-zen-stream3 sudo[5011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 11 09:02:40 ifi-zen-stream3 sudo[5011]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:40 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:40.803 E esdk select error: 4 -> Interrupted system call Dec 11 09:02:40 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:40.804 I app Exiting with error = 0 Dec 11 09:02:40 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:40.805 I api SpFree() Dec 11 09:02:40 ifi-zen-stream3 play[4420]: 2025-12-11 09:02:40.805 I api SpFree [returned value: 0] Dec 11 09:02:40 ifi-zen-stream3 play[4420]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 11 09:02:40 ifi-zen-stream3 play[4420]: [Info] ConvSwitch: ConvSwitchInit Dec 11 09:02:40 ifi-zen-stream3 play[4420]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 11 09:02:40 ifi-zen-stream3 play[4420]: [Info] MetaClient: MetaClientInit Dec 11 09:02:40 ifi-zen-stream3 play[4420]: [Info] ConvSwitch: ConvSwitchExit Dec 11 09:02:40 ifi-zen-stream3 play[4420]: [Info] MetaClient: MetaClientExit Dec 11 09:02:40 ifi-zen-stream3 node[471]: Closing connection with the client spotify Dec 11 09:02:40 ifi-zen-stream3 node[484]: ConvSwitch: Drop client spotify Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 11 09:02:40 ifi-zen-stream3 sudo[5011]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:40 ifi-zen-stream3 sudo[5014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: Spotify Connect Service Started Dec 11 09:02:40 ifi-zen-stream3 sudo[5014]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 11 09:02:40 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 11 09:02:40 ifi-zen-stream3 sudo[5014]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:40 ifi-zen-stream3 volumio[1387]: info: Spotify Connect Service Started Dec 11 09:02:41 ifi-zen-stream3 play[5021]: output: volumio mixer: hw:0 Dec 11 09:02:41 ifi-zen-stream3 node[484]: ConvSwitch: Add client spotify Dec 11 09:02:41 ifi-zen-stream3 node[471]: A new connection has been established. Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.138 I api SpRegisterDebugCallbacks [returned value: 0] Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.139 I api SpInit(0xffe179a0, API v66) Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.139 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.139 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 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.140 I esdk Successfully registered dns callback: dns_lookup_callback Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.140 I download Using 128 kB for cache buffer Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.140 I download Using 621 kB for compressed audio buffer Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.140 I esdk Successfully registered error callback Dec 11 09:02:41 ifi-zen-stream3 volumio[1387]: info: VolumeController::SetAlsaVolume100 Dec 11 09:02:41 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 11 09:02:41 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:41 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:41 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.161 I esdk Creating IPv4 socket (domain 2) Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I esdk Creating IPv4 socket (domain 2) Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I api SpInit [returned value: 0] Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I api SpRegisterConnectionCallbacks(0xffe17988, (nil)) Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I api SpRegisterPlaybackCallbacks(0xffe17970, (nil)) Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.162 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.169 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 11 09:02:41 ifi-zen-stream3 play[5021]: 2025-12-11 09:02:41.169 I app Initialized! Dec 11 09:02:41 ifi-zen-stream3 node[484]: ConvSwitch: Add client tidal Dec 11 09:02:41 ifi-zen-stream3 tidal_connect_application[5034]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 11 09:02:41 ifi-zen-stream3 tidal_connect_application[5034]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 09:02:41 ifi-zen-stream3 tidal_connect_application[5034]: *** WARNING *** For more information see Dec 11 09:02:41 ifi-zen-stream3 ifi_metadata[4947]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 11 09:02:41 ifi-zen-stream3 ifi_metadata[4947]: [Info] MetaClient: MetaClientInit Dec 11 09:02:41 ifi-zen-stream3 ifi_metadata[4947]: Connected Dec 11 09:02:42 ifi-zen-stream3 mpd[5001]: Dec 11 09:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 09:02:42 ifi-zen-stream3 systemd[1]: Started Music Player Daemon. Dec 11 09:02:42 ifi-zen-stream3 sudo[4995]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:42 ifi-zen-stream3 sudo[4993]: pam_unix(sudo:session): session closed for user root Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: Job for mpd.socket failed. Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: Restarted MPD. Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: error: MPD error: The expression evaluated to a falsy value: Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: assert.ok(self.idling) Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: error: The expression evaluated to a falsy value: Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: assert.ok(self.idling) Dec 11 09:02:42 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: error: updateQueue error: null Dec 11 09:02:42 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 11 09:02:42 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::serviceStop Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::serviceStop Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioStop Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:42 ifi-zen-stream3 volumio[1387]: info: Roon Ready Stop Dec 11 09:02:43 ifi-zen-stream3 volumiologrotate[512]: ls: cannot access '/var/log/samba/log.wb-ZEN': No such file or directory Dec 11 09:02:43 ifi-zen-stream3 volumiologrotate[512]: ls: cannot access 'STREAM3': No such file or directory Dec 11 09:02:45 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getMenuItems Dec 11 09:02:45 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getMenuItems Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 09:02:47 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 09:02:48 ifi-zen-stream3 volumio[1387]: info: ++++--++++ callMethod: {"type":"controller","endpoint":"audio_interface/alsa_controller","method":"saveAlsaOptions","data":{"output_device":{"value":"-1","label":"USB: Not Detected"}}} Dec 11 09:02:48 ifi-zen-stream3 volumio[1387]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 09:02:48 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: 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/歌曲/02 - Divertimento 88-24.flac","albumart":"/albumart?cacheid=980_1765443630306&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=980_1765443630304&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=980_1765443630305&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=980_1765443630305&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=980_1765443630305&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=980_1765443630305&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=980_1765443630305&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=980_1765443630305&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=980_1765443630306&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=980_1765443630306&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=980_1765443630306&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"}],"index":7} Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Preload queue cleared Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::ClearQueue Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::clearPlayQueue Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::saveQueue Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::addQueueItems Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::addQueueItems Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Preload queue cleared Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::saveQueue Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::updateTrackBlock Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrackBlock Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ConvSwitch: Sent Play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPlay Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::play index 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::addQueueItems Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::addQueueItems Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Preload queue cleared Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::stop Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushQueue Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::saveQueue Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::play index undefined Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::updateTrackBlock Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrackBlock Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::startPlaybackTimer Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ConvSwitch: Get message: success Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand stop took 9 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces system playlist update Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Ignoring MPD Status Update Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand clear took 5 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac" Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces system playlist update Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Ignoring MPD Status Update Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: error: updateQueue error: null Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces system playlist update Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Ignoring MPD Status Update Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 13ms Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand add "USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac" took 10 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 9ms Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces system playlist update Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Ignoring MPD Status Update Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 18ms Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand play took 13 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 15ms Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:52 ifi-zen-stream3 kernel: wm8804 0-003b: wm8804_hw_params rate: 0x15888 format: 0x6 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 24 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: ---------------------------- MPD announces state update: player Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::getState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 209 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 190 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 12 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: 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":"1312 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac","trackType":"flac"} Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus stop Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 245ms Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand status took 23 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":788,"duration":182,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1325 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac","trackType":"flac"} Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: 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":"1387 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac","trackType":"flac"} Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 322ms Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 124ms Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: sendMpdCommand playlistinfo took 85 milliseconds Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ControllerMpd::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::servicePushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: 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":"1387 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 88kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac","trackType":"flac"} Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: verbose: CURRENT POSITION 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreStateMachine::pushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CorePlayQueue::getTrack 7 Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:02:52 ifi-zen-stream3 volumio[1387]: info: ------------------------------ 150ms Dec 11 09:02:53 ifi-zen-stream3 volumio[1387]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/TrondheimSolistene/DIVERTIMENTI/0f950762-b50e-4f2c-8529-825e649bb446.jpg' Dec 11 09:02:53 ifi-zen-stream3 volumio[1387]: info: MetaClient: Sent Meta Dec 11 09:02:53 ifi-zen-stream3 volumio[1387]: info: MetaClient: Get message: success Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: Error: connect ETIMEDOUT 108.160.167.174:443 Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: errno: -110, Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: code: 'ETIMEDOUT', Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: syscall: 'connect', Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: address: '108.160.167.174', Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: port: 443 Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: } Dec 11 09:02:59 ifi-zen-stream3 volumio[1387]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 09:03:00 ifi-zen-stream3 sudo[5308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-11 09:02 Dec 11 09:03:00 ifi-zen-stream3 sudo[5308]: 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"