-- Logs begin at Sat 2025-11-15 07:41:13 UTC, end at Sat 2025-12-13 02:45:10 UTC. -- Dec 13 02:44:00 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 13 02:44:00 ifi-zen-stream3 sudo[4307]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:00 ifi-zen-stream3 sudo[4300]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: Spotify Connect Service Started Dec 13 02:44:00 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 13 02:44:00 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:00 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 13 02:44:00 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 13 02:44:00 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 13 02:44:00 ifi-zen-stream3 sudo[4307]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/TrondheimSolistene/DIVERTIMENTI/5eb2bd32-4eb3-43ad-8ee5-7b12dc9109af.jpg' Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: Spotify Connect Service Started Dec 13 02:44:00 ifi-zen-stream3 play[4313]: output: volumio mixer: hw:5 Dec 13 02:44:00 ifi-zen-stream3 node[488]: ConvSwitch: Add client spotify Dec 13 02:44:00 ifi-zen-stream3 node[486]: A new connection has been established. Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.332 I api SpRegisterDebugCallbacks [returned value: 0] Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.332 I api SpInit(0xffdf3760, API v66) Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.332 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.333 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 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.333 I esdk Successfully registered dns callback: dns_lookup_callback Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.333 I download Using 128 kB for cache buffer Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.333 I download Using 621 kB for compressed audio buffer Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.333 I esdk Successfully registered error callback Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.345 I esdk Creating IPv4 socket (domain 2) Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I esdk Creating IPv4 socket (domain 2) Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I api SpInit [returned value: 0] Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I api SpRegisterConnectionCallbacks(0xffdf3748, (nil)) Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I api SpRegisterPlaybackCallbacks(0xffdf3730, (nil)) Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.346 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.353 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 13 02:44:00 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:00.353 I app Initialized! Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: VolumeController::SetAlsaVolume100 Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:00 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:00 ifi-zen-stream3 node[488]: ConvSwitch: Add client tidal Dec 13 02:44:00 ifi-zen-stream3 tidal_connect_application[4324]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 13 02:44:00 ifi-zen-stream3 tidal_connect_application[4324]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 13 02:44:00 ifi-zen-stream3 tidal_connect_application[4324]: *** WARNING *** For more information see Dec 13 02:44:00 ifi-zen-stream3 ifi_metadata[4212]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 13 02:44:00 ifi-zen-stream3 ifi_metadata[4212]: [Info] MetaClient: MetaClientInit Dec 13 02:44:00 ifi-zen-stream3 ifi_metadata[4212]: Connected Dec 13 02:44:01 ifi-zen-stream3 mpd[4268]: Dec 13 02:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 02:44:01 ifi-zen-stream3 systemd[1]: Started Music Player Daemon. Dec 13 02:44:01 ifi-zen-stream3 sudo[4259]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:01 ifi-zen-stream3 sudo[4261]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:01 ifi-zen-stream3 volumio[1434]: info: Restarted MPD. Dec 13 02:44:01 ifi-zen-stream3 volumio[1434]: info: Restarted MPD. Dec 13 02:44:01 ifi-zen-stream3 volumio[1434]: error: MPD error: The expression evaluated to a falsy value: Dec 13 02:44:01 ifi-zen-stream3 volumio[1434]: assert.ok(self.idling) Dec 13 02:44:01 ifi-zen-stream3 volumio[1434]: error: The expression evaluated to a falsy value: Dec 13 02:44:01 ifi-zen-stream3 volumio[1434]: assert.ok(self.idling) Dec 13 02:44:01 ifi-zen-stream3 volumio[1434]: error: updateQueue error: null Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioStop Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioStop Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: ++++--++++ playItemsList: {"item":{"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=823_1765593772305&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=823_1765593772304&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772306&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=823_1765593772306&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"}],"index":5} Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Preload queue cleared Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::ClearQueue Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::clearPlayQueue Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::saveQueue Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushQueue Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::addQueueItems Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::addQueueItems Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Preload queue cleared Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushQueue Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::saveQueue Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::updateTrackBlock Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrackBlock Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: ConvSwitch: Sent Play Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPlay Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::play index 5 Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:02 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::addQueueItems Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::addQueueItems Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Preload queue cleared Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushQueue Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::saveQueue Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::play index undefined Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::updateTrackBlock Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrackBlock Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::startPlaybackTimer Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ConvSwitch: Get message: success Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand stop took 12 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces system playlist update Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Ignoring MPD Status Update Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand clear took 4 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac" Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces system playlist update Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Ignoring MPD Status Update Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: error: updateQueue error: null Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces system playlist update Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Ignoring MPD Status Update Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 12ms Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand add "USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac" took 9 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 8ms Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand play Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces system playlist update Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Ignoring MPD Status Update Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 14ms Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand play took 10 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 10ms Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 226 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 232 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: 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":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService play Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus stop Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 259ms Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 23 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 4 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: 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":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService play Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus play Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Received an update from plugin. extracting info from payload Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 308ms Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 43 milliseconds Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: 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":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService play Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus play Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Received an update from plugin. extracting info from payload Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: 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":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService play Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus play Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: Received an update from plugin. extracting info from payload Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 145ms Dec 13 02:44:03 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 126ms Dec 13 02:44:04 ifi-zen-stream3 volumio[1434]: info: MetaClient: Sent Meta Dec 13 02:44:04 ifi-zen-stream3 volumio[1434]: info: MetaClient: Get message: success Dec 13 02:44:09 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 13 02:44:09 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 13 02:44:09 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 13 02:44:20 ifi-zen-stream3 streamer-irq[1436]: time collapsed: 155, toggle leds! Dec 13 02:44:20 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 13 02:44:20 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 13 02:44:20 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 13 02:44:22 ifi-zen-stream3 streamer-irq[1436]: /usr/bin/ifi-toggle-led.sh: line 148: /usr/bin/mqa-authenticator: No such file or directory Dec 13 02:44:22 ifi-zen-stream3 streamer-irq[1436]: time collapsed: 100, toggle leds! Dec 13 02:44:32 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 13 02:44:32 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 13 02:44:32 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 13 02:44:32 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 13 02:44:32 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: ++++--++++ pause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stPlaybackTimer Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::servicePause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand pause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand pause took 4 milliseconds Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 8 milliseconds Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 4 milliseconds Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30481,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1447 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService pause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus pause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stPlaybackTimer Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30481,"duration":182,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1447 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService pause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus pause Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stPlaybackTimer Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 72ms Dec 13 02:44:32 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 69ms Dec 13 02:44:34 ifi-zen-stream3 kernel: usb 4-1.3: USB disconnect, device number 5 Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: ---------------------------- USB Audio Device Detached Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::Close All Modals sent Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Preparing to save Alsa Options, stopping services first Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPause Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pause Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Saving Audio Output to: {"disallowPush":false,"output_device":{"value":"0,0","label":"SPDIF"}} Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , forceDoP Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Found match in Cards Database: setting mixer for card SPDIF Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Ignoring Mixers Options Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Device 0,0 does not have any Mixer Control Available Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Restoring Previous Volume level: 100 false false Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Updating Volume Controller Parameters: Device: 0,0 Name: SPDIF Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Preparing to generate the ALSA configuration file Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: wizard , refreshWizarOutputDevices Dec 13 02:44:34 ifi-zen-stream3 volumio[1434]: info: Asound.conf file written Dec 13 02:44:34 ifi-zen-stream3 sudo[4762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 13 02:44:34 ifi-zen-stream3 sudo[4762]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:34 ifi-zen-stream3 sudo[4762]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: No state is present for card rkifispdif Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: Found hardware: "rk-ifi-spdif" "" "" "" "" Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: Hardware is initialized using a generic method Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: No state is present for card rkifispdif Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Tidal connect: Output device has changed Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Output device has changed, restarting MPD Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Qobuz connect: Output device has changed Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Roon Ready: Output device has changed Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Spotify: Output device has changed Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Tidal connect: Output device has changed Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Output device has changed, restarting MPD Dec 13 02:44:35 ifi-zen-stream3 sudo[4767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 13 02:44:35 ifi-zen-stream3 sudo[4767]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:35 ifi-zen-stream3 sudo[4767]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Qobuz connect: Output device has changed Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Roon Ready: Output device has changed Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Spotify: Output device has changed Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: MPD Permissions set Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 sudo[4769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 sudo[4769]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:35 ifi-zen-stream3 sudo[4769]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: MPD Permissions set Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Starting RoonReady Sync Dec 13 02:44:35 ifi-zen-stream3 sudo[4786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 13 02:44:35 ifi-zen-stream3 sudo[4786]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: Starting RoonReady Sync Dec 13 02:44:35 ifi-zen-stream3 sudo[4787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 13 02:44:35 ifi-zen-stream3 sudo[4787]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:35 ifi-zen-stream3 sudo[4790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Dec 13 02:44:35 ifi-zen-stream3 sudo[4790]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:35 ifi-zen-stream3 sudo[4795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Dec 13 02:44:35 ifi-zen-stream3 sudo[4795]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:35 ifi-zen-stream3 node[488]: ConvSwitch: Drop client roon-ready Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Roon Ready Service... Dec 13 02:44:35 ifi-zen-stream3 node[486]: Closing connection with the client roon-ready Dec 13 02:44:35 ifi-zen-stream3 node[488]: ConvSwitch: Drop client qobuz-connect Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Dec 13 02:44:35 ifi-zen-stream3 node[486]: Closing connection with the client qobuz-connect Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Roon Ready Service. Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Dec 13 02:44:35 ifi-zen-stream3 sudo[4786]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:35 ifi-zen-stream3 systemd[1]: Started iFi Streamer Roon Ready Service. Dec 13 02:44:35 ifi-zen-stream3 sudo[4787]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: RoonReady Started Dec 13 02:44:35 ifi-zen-stream3 sudo[4795]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:35 ifi-zen-stream3 play[4799]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Dec 13 02:44:35 ifi-zen-stream3 sudo[4790]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:35 ifi-zen-stream3 volumio[1434]: info: RoonReady Started Dec 13 02:44:36 ifi-zen-stream3 node[488]: ConvSwitch: Add client roon-ready Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: ConvSwitch: Get message: request:pause Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioStop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::updateTrackBlock Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrackBlock Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stPlaybackTimer Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::serviceStop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::serviceStop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::stop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioStop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand stop took 13 milliseconds Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 3 milliseconds Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 5 milliseconds Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 46ms Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Roon Ready playback start Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioStop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Roon Ready metadata receiver is on Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 48kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac","trackType":"flac"} Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 5 Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: error: ControllerMpd::pushError: TypeError: Cannot read property 'indexOf' of null Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 71ms Dec 13 02:44:36 ifi-zen-stream3 sudo[4850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 13 02:44:36 ifi-zen-stream3 sudo[4850]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 13 02:44:36 ifi-zen-stream3 node[486]: Closing connection with the client tidal-connect Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:36 ifi-zen-stream3 node[488]: ConvSwitch: Drop client tidal Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 13 02:44:36 ifi-zen-stream3 sudo[4850]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Tidal Connect Service Started Dec 13 02:44:36 ifi-zen-stream3 sudo[4858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Dec 13 02:44:36 ifi-zen-stream3 sudo[4858]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:36 ifi-zen-stream3 node[486]: A new connection has been established. Dec 13 02:44:36 ifi-zen-stream3 node[486]: Closing connection with the client tidal-connect Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Tidal Connect Service... Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Tidal Connect Service. Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Connect Service. Dec 13 02:44:36 ifi-zen-stream3 sudo[4858]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Started iFi Streamer Tidal Metadata Service. Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Tidal Connect Service Started Dec 13 02:44:36 ifi-zen-stream3 node[486]: A new connection has been established. Dec 13 02:44:36 ifi-zen-stream3 sudo[4873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 13 02:44:36 ifi-zen-stream3 sudo[4873]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:36 ifi-zen-stream3 systemd[1]: Started iFi Streamer Qobuz Connect Service. Dec 13 02:44:36 ifi-zen-stream3 sudo[4873]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Qobuz Connect Service Started Dec 13 02:44:36 ifi-zen-stream3 sudo[4881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Dec 13 02:44:36 ifi-zen-stream3 sudo[4881]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:36 ifi-zen-stream3 sudo[4881]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:36 ifi-zen-stream3 volumio[1434]: info: Qobuz Connect Service Started Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.993 [4900.4900] DEBUG SampleApp: Starting sample application Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.995 [4900.4900] DEBUG SampleApp: Device Name: ZEN Stream3 Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.996 [4900.4900] DEBUG SampleApp: Manufacturer: Sample manufacturer Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.996 [4900.4900] DEBUG SampleApp: Model: iFi Streamer Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.996 [4900.4900] DEBUG SampleApp: Serial Number: ifi:2a:c4:7d:47:b5:7b Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.996 [4900.4900] DEBUG SampleApp: Audio output device: volumio Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.996 [4900.4900] DEBUG SampleApp: Volume control device: default Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.996 [4900.4900] DEBUG SampleApp: Volume control element: Master Dec 13 02:44:36 ifi-zen-stream3 play[4880]: 20251213 02:44:36.996 [4900.4900] DEBUG SampleApp: Local config server port: 8000 Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.001 [4900.4900] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.001 [4900.4900] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Dec 13 02:44:37 ifi-zen-stream3 play[4880]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 13 02:44:37 ifi-zen-stream3 node[486]: A new connection has been established. Dec 13 02:44:37 ifi-zen-stream3 play[4880]: [Info] MetaClient: MetaClientInit Dec 13 02:44:37 ifi-zen-stream3 play[4880]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 13 02:44:37 ifi-zen-stream3 node[488]: ConvSwitch: Add client qobuz-connect Dec 13 02:44:37 ifi-zen-stream3 sudo[4904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 13 02:44:37 ifi-zen-stream3 sudo[4904]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:37 ifi-zen-stream3 play[4880]: [Info] ConvSwitch: ConvSwitchInit Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.108 [4900.4900] DEBUG ActiveStateManager: [0xab4c4098]: Creating new Active State Manager Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.108 [4900.4900] DEBUG NetworkActivityTracker: [0xab4c4108]: Creating new Network Activity Tracker Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.108 [4900.4900] DEBUG EndpointManager: [0xab4c5080]: Creating Endpoint Manager Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG PlaybackSessionManager: [0xab4c5470]: Creating Playback Session Manager Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG BackoffTimer: [0xab4c55e8]: Creating Backoff Timer Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG MediaEngine: [0xab4c5708]: Creating new Media Engine Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG AudioRenderer: [0xab4c5928]: Creating Audio Renderer Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] TRACE MediaEngineCommandSequencer: [0xab4c5a80]: Creating Media Engine Command Sequencer Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG PlaybackControlsManager: [0xab4c5ae0]: Creating new Playback Controls Manager Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG VolumeManager: [0xab4c5b70]: Creating new Volume Manager Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG ProtocolHandler: [0xab4c5e48]: Creating Protocol Handler Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG MessageReceiver: [0xab4c5ee8]: Creating Message Receiver Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG RendererActivationController: [0xab4c6060]: Creating new Renderer Activation Controller Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] TRACE RendererActivationCommandSequencer: [0xab4c6108]: Creating Renderer Activation Command Sequencer Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG ReportingManager: [0xab4c6218]: Creating new Reporting Manager Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG StreamingEventReporter: [0xab4c6260]: Creating Streaming Event Reporter Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG BackoffTimer: [0xab4c6340]: Creating Backoff Timer Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG TokenExpiryController: [0xab4c6450]: Creating Token Expiry Controller Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG UserInactivityManager: [0xab4c64a0]: Creating new User Inactivity Manager Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] INFO VolumeManager: [0xab4c5b70]: Setting new playback volume: 100 Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] INFO VolumeManager: [0xab4c5b70]: Setting new mute state: 1 Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] DEBUG SampleApp: Qobuz Connect has been initialized Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] TRACE SampleApp: Running event loop Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] INFO QobuzConnect: [0xab4c6540]: Client initialized! Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.109 [4900.4900] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream3, service name: _qobuz-connect._tcp Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.124 [4900.4900] DEBUG SampleApp: Avahi client state changed: RUNNING Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.128 [4900.4900] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.135 [4900.4900] INFO LocalConfigManager: [0xab4c5450]: Starting Local Configuration server Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.135 [4900.4900] INFO SampleApp: Starting Local configuration server Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.136 [4900.4900] INFO SampleApp: Playback mute state changed: 1 Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.137 [4900.4900] ERROR SampleApp: Unable to set mute state, no ALSA volume element Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.137 [4900.4900] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Dec 13 02:44:37 ifi-zen-stream3 sudo[4907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Starting Internet Detect for iFi Streamer... Dec 13 02:44:37 ifi-zen-stream3 sudo[4907]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.148 [4900.4900] INFO SampleApp: Playback volume changed: 100 Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.148 [4900.4900] ERROR SampleApp: Unable to set volume, no ALSA volume element Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Stopping Music Player Daemon... Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 13 02:44:37 ifi-zen-stream3 sudo[4904]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:37 ifi-zen-stream3 sudo[4907]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:37 ifi-zen-stream3 sudo[4925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 13 02:44:37 ifi-zen-stream3 sudo[4925]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:37 ifi-zen-stream3 sudo[4928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Dec 13 02:44:37 ifi-zen-stream3 sudo[4928]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: mpd.socket: Succeeded. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Closed mpd.socket. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Stopping mpd.socket. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Failed to listen on mpd.socket. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: mpd.service: Succeeded. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Stopped Music Player Daemon. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Listening on mpd.socket. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Starting Music Player Daemon... Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: ifi-system-internet.service: Succeeded. Dec 13 02:44:37 ifi-zen-stream3 systemd[1]: Started Internet Detect for iFi Streamer. Dec 13 02:44:37 ifi-zen-stream3 sudo[4931]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 02:44:37 ifi-zen-stream3 sudo[4931]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:37 ifi-zen-stream3 sudo[4931]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:37 ifi-zen-stream3 node[486]: A new connection has been established. Dec 13 02:44:37 ifi-zen-stream3 play[4880]: 20251213 02:44:37.909 [4900.4900] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Dec 13 02:44:38 ifi-zen-stream3 sudo[4948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 13 02:44:38 ifi-zen-stream3 sudo[4948]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:38 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:38.671 E esdk select error: 4 -> Interrupted system call Dec 13 02:44:38 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:38.672 I app Exiting with error = 0 Dec 13 02:44:38 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:38.672 I api SpFree() Dec 13 02:44:38 ifi-zen-stream3 play[4313]: 2025-12-13 02:44:38.673 I api SpFree [returned value: 0] Dec 13 02:44:38 ifi-zen-stream3 play[4313]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Dec 13 02:44:38 ifi-zen-stream3 play[4313]: [Info] ConvSwitch: ConvSwitchInit Dec 13 02:44:38 ifi-zen-stream3 play[4313]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 13 02:44:38 ifi-zen-stream3 play[4313]: [Info] MetaClient: MetaClientInit Dec 13 02:44:38 ifi-zen-stream3 play[4313]: [Info] ConvSwitch: ConvSwitchExit Dec 13 02:44:38 ifi-zen-stream3 play[4313]: [Info] MetaClient: MetaClientExit Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::getcurrentVolume Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioRetrievevolume Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 13 02:44:38 ifi-zen-stream3 node[488]: ConvSwitch: Drop client spotify Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 13 02:44:38 ifi-zen-stream3 node[486]: Closing connection with the client spotify Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 13 02:44:38 ifi-zen-stream3 sudo[4955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 13 02:44:38 ifi-zen-stream3 sudo[4955]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 13 02:44:38 ifi-zen-stream3 sudo[4948]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: Spotify Connect Service Started Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: Stopping iFi Streamer Spotify Connect Service... Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: Stopped iFi Streamer Spotify Connect Service. Dec 13 02:44:38 ifi-zen-stream3 systemd[1]: Started iFi Streamer Spotify Connect Service. Dec 13 02:44:38 ifi-zen-stream3 sudo[4955]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:38 ifi-zen-stream3 volumio[1434]: info: Spotify Connect Service Started Dec 13 02:44:38 ifi-zen-stream3 play[4961]: output: volumio mixer: hw:0 Dec 13 02:44:38 ifi-zen-stream3 node[488]: ConvSwitch: Add client spotify Dec 13 02:44:38 ifi-zen-stream3 node[486]: A new connection has been established. Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.026 I api SpRegisterDebugCallbacks [returned value: 0] Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.026 I api SpInit(0xffb691b0, API v66) Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.026 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.027 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 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.027 I esdk Successfully registered dns callback: dns_lookup_callback Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.027 I download Using 128 kB for cache buffer Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.027 I download Using 621 kB for compressed audio buffer Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.027 I esdk Successfully registered error callback Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.039 I esdk Creating IPv4 socket (domain 2) Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.039 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.039 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.039 I esdk Creating IPv4 socket (domain 2) Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.040 I api SpInit [returned value: 0] Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.040 I api SpRegisterConnectionCallbacks(0xffb69198, (nil)) Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.040 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.040 I api SpRegisterConnectionCallbacks [returned value: 0] Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.040 I api SpRegisterPlaybackCallbacks(0xffb69180, (nil)) Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.040 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.040 I api SpRegisterPlaybackCallbacks [returned value: 0] Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.046 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Dec 13 02:44:39 ifi-zen-stream3 play[4961]: 2025-12-13 02:44:39.046 I app Initialized! Dec 13 02:44:39 ifi-zen-stream3 volumio[1434]: info: VolumeController::SetAlsaVolume100 Dec 13 02:44:39 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Dec 13 02:44:39 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:39 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:39 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:39 ifi-zen-stream3 node[488]: ConvSwitch: Add client tidal Dec 13 02:44:39 ifi-zen-stream3 tidal_connect_application[4974]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Dec 13 02:44:39 ifi-zen-stream3 tidal_connect_application[4974]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 13 02:44:39 ifi-zen-stream3 tidal_connect_application[4974]: *** WARNING *** For more information see Dec 13 02:44:39 ifi-zen-stream3 ifi_metadata[4867]: [Info] MetaClient: MetaClient SDK -- v1.02 Dec 13 02:44:39 ifi-zen-stream3 ifi_metadata[4867]: [Info] MetaClient: MetaClientInit Dec 13 02:44:39 ifi-zen-stream3 ifi_metadata[4867]: Connected Dec 13 02:44:40 ifi-zen-stream3 mpd[4941]: Dec 13 02:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 02:44:40 ifi-zen-stream3 systemd[1]: Started Music Player Daemon. Dec 13 02:44:40 ifi-zen-stream3 sudo[4928]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:40 ifi-zen-stream3 sudo[4925]: pam_unix(sudo:session): session closed for user root Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: Restarted MPD. Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: Job for mpd.socket failed. Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: See "systemctl status mpd.socket" and "journalctl -xe" for details. Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: error: MPD error: The expression evaluated to a falsy value: Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: assert.ok(self.idling) Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: error: The expression evaluated to a falsy value: Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: assert.ok(self.idling) Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: error: updateQueue error: null Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioStop Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::serviceStop Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::serviceStop Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioStop Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:40 ifi-zen-stream3 volumio[1434]: info: Roon Ready Stop Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ++++--++++ playItemsList: {"item":{"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=823_1765593772305&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=823_1765593772304&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772305&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=823_1765593772306&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=823_1765593772306&path=%2Fmnt%2FUSB%2FSamsung_USB%2F%E6%AD%8C%E6%9B%B2&icon=music&metadata=false"}],"index":4} Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Preload queue cleared Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::ClearQueue Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::clearPlayQueue Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::saveQueue Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushQueue Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::addQueueItems Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::addQueueItems Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Preload queue cleared Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(352kHz)Divertimento 352.8-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(48kHz)Divertimento 48-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/10 Sisters Of The Moon-Magenta.OFS.mqb.auth1.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(88kHz)Divertimento 88-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushQueue Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::saveQueue Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::updateTrackBlock Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrackBlock Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ConvSwitch: Sent Play Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPlay Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::play index 4 Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::addQueueItems Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::addQueueItems Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Preload queue cleared Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/01 - Divertimento 48-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 192-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/02 - Divertimento 88-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/(176kHz)Tchaikovsky Hopak 176-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Adding Item to queue: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Using cached record of: music-library/USB/Samsung_USB/歌曲/Carmen 44.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::stop Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushQueue Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::saveQueue Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::play index undefined Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::updateTrackBlock Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrackBlock Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::startPlaybackTimer Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ConvSwitch: Get message: success Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand stop took 7 milliseconds Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces system playlist update Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Ignoring MPD Status Update Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand clear took 8 milliseconds Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac" Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces system playlist update Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Ignoring MPD Status Update Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: error: updateQueue error: null Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces system playlist update Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Ignoring MPD Status Update Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 12ms Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand add "USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac" took 10 milliseconds Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 9ms Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand play Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces system playlist update Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Ignoring MPD Status Update Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 16ms Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand play took 12 milliseconds Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 13ms Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:48 ifi-zen-stream3 kernel: wm8804 0-003b: wm8804_hw_params rate: 0x17700 format: 0x6 Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 18 milliseconds Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:48 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 25 milliseconds Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 96kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac","trackType":"flac"} Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService play Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus stop Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 53ms Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: ---------------------------- MPD announces state update: player Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::getState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand status Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 26 milliseconds Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand status took 5 milliseconds Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioGetState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 96kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac","trackType":"flac"} Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService play Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus play Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: Received an update from plugin. extracting info from payload Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 86ms Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: ControllerMpd::parseTrackInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1484 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 96kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac","trackType":"flac"} Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService play Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus play Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: Received an update from plugin. extracting info from payload Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ControllerMpd::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::servicePushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1484 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree 96kHz","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"USB/Samsung_USB/歌曲/(96kHz)Divertimento 96-24.flac","trackType":"flac"} Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: verbose: CURRENT POSITION 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState stateService play Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::syncState currentStatus play Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: Received an update from plugin. extracting info from payload Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreStateMachine::pushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CorePlayQueue::getTrack 4 Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::volumioPushState Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 113ms Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: info: ------------------------------ 93ms Dec 13 02:44:49 ifi-zen-stream3 volumio[1434]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/TrondheimSolistene/DIVERTIMENTI/5eb2bd32-4eb3-43ad-8ee5-7b12dc9109af.jpg' Dec 13 02:44:50 ifi-zen-stream3 volumio[1434]: info: MetaClient: Sent Meta Dec 13 02:44:50 ifi-zen-stream3 volumio[1434]: info: MetaClient: Get message: success Dec 13 02:44:56 ifi-zen-stream3 volumiologrotate[493]: ls: cannot access '/var/log/samba/log.wb-ZEN': No such file or directory Dec 13 02:44:56 ifi-zen-stream3 volumiologrotate[493]: ls: cannot access 'STREAM3': No such file or directory Dec 13 02:45:04 ifi-zen-stream3 streamer-irq[1436]: time collapsed: 139, toggle leds! Dec 13 02:45:06 ifi-zen-stream3 streamer-irq[1436]: /usr/bin/ifi-toggle-led.sh: line 148: /usr/bin/mqa-authenticator: No such file or directory Dec 13 02:45:06 ifi-zen-stream3 streamer-irq[1436]: time collapsed: 216, toggle leds! Dec 13 02:45:06 ifi-zen-stream3 streamer-irq[1436]: time collapsed: 4, toggle leds! Dec 13 02:45:08 ifi-zen-stream3 streamer-irq[1436]: /usr/bin/ifi-toggle-led.sh: line 148: /usr/bin/mqa-authenticator: No such file or directory Dec 13 02:45:08 ifi-zen-stream3 streamer-irq[1436]: time collapsed: 177, toggle leds! Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: Error: connect ETIMEDOUT 75.126.33.156:443 Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: errno: -110, Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: code: 'ETIMEDOUT', Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: syscall: 'connect', Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: address: '75.126.33.156', Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: port: 443 Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: } Dec 13 02:45:09 ifi-zen-stream3 volumio[1434]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 02:45:10 ifi-zen-stream3 sudo[5372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-13 02:44 Dec 13 02:45:10 ifi-zen-stream3 sudo[5372]: 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"