-- Logs begin at Sat 2025-11-15 14:00:41 WIB, end at Sat 2026-03-28 15:06:58 WIB. -- Mar 28 15:05:01 ifi-zen-stream volumio[1190]: info: ++++--++++ getMyCollectionStats: null Mar 28 15:05:01 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 15:05:05 ifi-zen-stream volumio[1190]: info: ++++--++++ getMyCollectionStats: null Mar 28 15:05:05 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 15:05:06 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:05:06 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:05:11 ifi-zen-stream volumio[1190]: info: ++++--++++ getBrowseSources: null Mar 28 15:05:11 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 15:05:11 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 15:05:11 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: ++++--++++ search: {"type":"any","value":"Wilco"} Mar 28 15:05:15 ifi-zen-stream volumio[1190]: Searching all installed plugins Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: , search Mar 28 15:05:15 ifi-zen-stream volumio[1190]: error: Could not retrieve plugin Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 28 15:05:15 ifi-zen-stream volumio[1190]: info: search in webradio selection! Mar 28 15:05:20 ifi-zen-stream volumio[1190]: error: Search in plugin webradio timed out Mar 28 15:05:20 ifi-zen-stream volumio[1190]: info: All search sources collected, pushing search results Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I download Download complete, read 12308360 B in 323297 ms Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I download spotify:track:4BID4qBOUahM5GbPScILxH: Download finished. size:12308360 cdn_offset:12308360 total_time:323297, avg_bw:52571kbps Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I audio UPCOMING : - Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I audio PLAYING : id: 41, DELIVERING, DOWNLOADING, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I audio PLAYING: DOWNLOADING => DOWNLOAD_DONE Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I audio UPCOMING : - Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I audio UPCOMING : - Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.907 I audio id: 0 : UPCOMING -> Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.908 I audio UPCOMING : - Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.908 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.908 I audio id: 42 : -> UPCOMING Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.908 I audio UPCOMING : id: 42, DELIVERY_NOT_STARTED, DOWNLOAD_NOT_STARTED, length_ms: 0 file.size: 0 file.has_key: 0 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 0 sample_rate: 0 Mar 28 15:05:20 ifi-zen-stream play[19107]: 2026-03-28 15:05:20.908 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:21 ifi-zen-stream play[19107]: 2026-03-28 15:05:21.406 I download Loading audio resource for 'spotify:track:4Djw6sC00SmvuRscqvCXpx' Mar 28 15:05:21 ifi-zen-stream play[19107]: 2026-03-28 15:05:21.407 I download Request for song url sent (channel_id:65, fail_count:0) Mar 28 15:05:21 ifi-zen-stream play[19107]: 2026-03-28 15:05:21.407 I download Request for song key sent (channel_id:41, fail_count:0) Mar 28 15:05:21 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:05:21 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.781 I download stream_error:0, key_error:0, success:1 Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.782 I download Storing key in memcache slot: 0 Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.782 I download downloading 'http://audio-ake.spotify.com.edgesuite.net/audio/XV7MPiyaw3qErsYcbkCX744hyx6BKp84hS4W5rVCP3kTGeUVW2i11T++PNgkyKe9Txfy%2FiQpsRl4r3AWN95CNg==?__token__=exp=1774771522~hmac=e29261b8c8cc88340fe4155c44f0427286127614604e00074fe182daef9c358f' from offset:0 size:0 Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.782 I download requesting stream 'http://audio-ake.spotify.com.edgesuite.net/audio/XV7MPiyaw3qErsYcbkCX744hyx6BKp84hS4W5rVCP3kTGeUVW2i11T++PNgkyKe9Txfy%2FiQpsRl4r3AWN95CNg==?__token__=exp=1774771522~hmac=e29261b8c8cc88340fe4155c44f0427286127614604e00074fe182daef9c358f' offset:0kb (size:0kb) Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.782 I esdk reusing the socket Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.782 I audio UPCOMING : id: 42, DELIVERY_NOT_STARTED, DOWNLOAD_NOT_STARTED, length_ms: 0 file.size: 0 file.has_key: 0 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 0 sample_rate: 0 Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.782 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.782 I audio UPCOMING : id: 42, DELIVERY_NOT_STARTED, DOWNLOAD_NOT_STARTED, length_ms: 0 file.size: 0 file.has_key: 0 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 0 sample_rate: 0 Mar 28 15:05:22 ifi-zen-stream play[19107]: 2026-03-28 15:05:22.783 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.658 I audio UPCOMING : id: 42, DELIVERY_NOT_STARTED, DOWNLOAD_NOT_STARTED, length_ms: 0 file.size: 0 file.has_key: 1 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 0 sample_rate: 0 Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.659 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.659 I audio UPCOMING : id: 42, DELIVERY_NOT_STARTED, DOWNLOAD_NOT_STARTED, length_ms: 0 file.size: 7822517 file.has_key: 1 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 0 sample_rate: 0 Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.659 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.659 I audio UPCOMING : id: 42, DELIVERY_NOT_STARTED, DOWNLOAD_NOT_STARTED, length_ms: 0 file.size: 7822517 file.has_key: 1 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 0 sample_rate: 0 Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.659 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.659 I audio UPCOMING: DOWNLOAD_NOT_STARTED => DOWNLOADING Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.659 I audio UPCOMING : id: 42, DELIVERY_NOT_STARTED, DOWNLOADING, length_ms: 0 file.size: 7822517 file.has_key: 1 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 0 sample_rate: 0 Mar 28 15:05:23 ifi-zen-stream play[19107]: 2026-03-28 15:05:23.659 I audio PLAYING : id: 41, DELIVERING, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:27 ifi-zen-stream play[19107]: 2026-03-28 15:05:27.908 W esdk SpPumpEvents() is called too slowly: 46507 ms for 100 calls Mar 28 15:05:31 ifi-zen-stream volumio[1190]: info: ++++--++++ browseLibrary: {"uri":"music-library"} Mar 28 15:05:31 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 15:05:31 ifi-zen-stream volumio[1190]: info: CURURI: music-library Mar 28 15:05:31 ifi-zen-stream volumio[1190]: info: Preload queue cleared Mar 28 15:05:32 ifi-zen-stream volumio[1190]: info: ++++--++++ browseLibrary: {"uri":"music-library/USB","prevUri":"music-library"} Mar 28 15:05:32 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 15:05:32 ifi-zen-stream volumio[1190]: info: CURURI: music-library/USB Mar 28 15:05:33 ifi-zen-stream volumio[1190]: info: Preload queue cleared Mar 28 15:05:33 ifi-zen-stream volumio[1190]: info: ++++--++++ browseLibrary: {"uri":"music-library/USB/Extreme_SSD","prevUri":"music-library/USB"} Mar 28 15:05:33 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 15:05:33 ifi-zen-stream volumio[1190]: info: CURURI: music-library/USB/Extreme_SSD Mar 28 15:05:34 ifi-zen-stream volumio[1190]: info: Preload queue cleared Mar 28 15:05:34 ifi-zen-stream volumio[1190]: info: ++++--++++ browseLibrary: {"uri":"music-library/USB/Extreme_SSD/Music","prevUri":"music-library/USB/Extreme_SSD"} Mar 28 15:05:34 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 15:05:34 ifi-zen-stream volumio[1190]: info: CURURI: music-library/USB/Extreme_SSD/Music Mar 28 15:05:34 ifi-zen-stream volumio[1190]: info: Preload queue cleared Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.035 I audio PLAYING: DELIVERING => DELIVERY_DONE Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.035 I esdk Notifying kSpPlaybackNotifyTrackDelivered Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.035 I app Track delivered Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.035 I audio UPCOMING : id: 42, DELIVERY_NOT_STARTED, DOWNLOADING, length_ms: 0 file.size: 7822517 file.has_key: 1 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 0 sample_rate: 0 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.035 I audio PLAYING : id: 41, DELIVERY_DONE, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: tremor channels: 2 sample_rate: 44100 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.035 I audio UPCOMING: NOT DELIVERING => DELIVERING Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.050 I audio UPCOMING : id: 42, DELIVERING, DOWNLOADING, length_ms: 200161 file.size: 7822517 file.has_key: 1 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.050 I audio PLAYING : id: 41, DELIVERY_DONE, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: tremor channels: 2 sample_rate: 44100 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.532 I audio UPCOMING : id: 42, DELIVERING, DOWNLOADING, length_ms: 200161 file.size: 7822517 file.has_key: 1 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.532 I audio PLAYING : id: 41, DELIVERY_DONE, DOWNLOAD_DONE, length_ms: 325510 file.size: 12308360 file.has_key: 1 playback_id: 1c1c0f54968a42c80a1c52291727417a uri: spotify:track:4BID4qBOUahM5GbPScILxH codec: tremor channels: 2 sample_rate: 44100 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.532 I audio Shifting track pipeline Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.532 I audio id: 41 : PLAYING-> Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.532 I audio id: 42 : UPCOMING -> PLAYING Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.532 I audio UPCOMING : - Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.532 I audio PLAYING : id: 42, DELIVERING, DOWNLOADING, length_ms: 200161 file.size: 7822517 file.has_key: 1 playback_id: 4319ff5ecbe225da4d6eb53ef5c165e5 uri: spotify:track:4Djw6sC00SmvuRscqvCXpx codec: - channels: 2 sample_rate: 44100 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.537 I esdk is_playing=1 is_prefetching=1 stream_requested=0 is_next_invalid=0 is_seeking=0 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.537 I esdk Shifting state, operation: 0, initial_playback_position: 0 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.537 I esdk State update, position: 0 previous position: 325510, empty: 0 counter: 0 source: 9 Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.538 I esdk time_to_first_update_requested: 30000 ms, next_push_ms_played set to: 30000 ms, periodic_update_time_ms requested: 600000 ms Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.538 I esdk Notifying kSpPlaybackNotifyMetadataChanged Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.538 I app Metadata changed: playing Whitney -- Back to the Wind Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.538 I app prev: One Good Reason, next: Not A Fan Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.538 I esdk Notifying kSpPlaybackNotifyTrackChanged Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.538 I app Track event: playing Whitney -- Back to the Wind Mar 28 15:05:38 ifi-zen-stream play[19107]: 2026-03-28 15:05:38.538 I app prev: One Good Reason, next: Not A Fan Mar 28 15:05:39 ifi-zen-stream play[19107]: 2026-03-28 15:05:39.411 I esdk Got state response Mar 28 15:05:39 ifi-zen-stream play[19107]: 2026-03-28 15:05:39.411 I esdk Notifying kSpPlaybackNotifyMetadataChanged Mar 28 15:05:39 ifi-zen-stream play[19107]: 2026-03-28 15:05:39.412 I app Metadata changed: playing Whitney -- Back to the Wind Mar 28 15:05:39 ifi-zen-stream play[19107]: 2026-03-28 15:05:39.412 I app prev: One Good Reason, next: Not A Fan Mar 28 15:05:45 ifi-zen-stream volumiologrotate[505]: ls: cannot access '/var/log/samba/log.wb-ZEN': No such file or directory Mar 28 15:05:45 ifi-zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Mar 28 15:05:45 ifi-zen-stream volumiologrotate[505]: ls: cannot access 'STREAM': No such file or directory Mar 28 15:05:46 ifi-zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Mar 28 15:05:46 ifi-zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Mar 28 15:05:47 ifi-zen-stream volumio[1190]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Mar 28 15:05:47 ifi-zen-stream volumio[1190]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Mar 28 15:05:47 ifi-zen-stream volumio[1190]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Mar 28 15:05:47 ifi-zen-stream volumio[1190]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Mar 28 15:05:47 ifi-zen-stream volumio[1190]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Mar 28 15:05:47 ifi-zen-stream volumio[1190]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Mar 28 15:05:47 ifi-zen-stream volumio[1190]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 28 15:05:47 ifi-zen-stream volumio[1190]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 28 15:05:51 ifi-zen-stream volumio[1190]: info: ++++--++++ browseLibrary: {"uri":"music-library/USB/Extreme_SSD/Music","prevUri":"music-library/USB/Extreme_SSD/Music"} Mar 28 15:05:51 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 28 15:05:51 ifi-zen-stream volumio[1190]: info: CURURI: music-library/USB/Extreme_SSD/Music Mar 28 15:05:51 ifi-zen-stream volumio[1190]: info: Preload queue cleared Mar 28 15:05:54 ifi-zen-stream volumio[1190]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Mar 28 15:05:54 ifi-zen-stream volumio[1190]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Mar 28 15:05:54 ifi-zen-stream volumio[1190]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Mar 28 15:05:54 ifi-zen-stream volumio[1190]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Mar 28 15:05:54 ifi-zen-stream volumio[1190]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Mar 28 15:05:54 ifi-zen-stream volumio[1190]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Mar 28 15:05:54 ifi-zen-stream volumio[1190]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 28 15:05:54 ifi-zen-stream volumio[1190]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 28 15:05:57 ifi-zen-stream volumio[1190]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Mar 28 15:05:57 ifi-zen-stream volumio[1190]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Mar 28 15:05:57 ifi-zen-stream volumio[1190]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Mar 28 15:05:57 ifi-zen-stream volumio[1190]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Mar 28 15:05:57 ifi-zen-stream volumio[1190]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Mar 28 15:05:57 ifi-zen-stream volumio[1190]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Mar 28 15:05:57 ifi-zen-stream volumio[1190]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 28 15:05:57 ifi-zen-stream volumio[1190]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 28 15:05:58 ifi-zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Mar 28 15:05:58 ifi-zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Mar 28 15:05:58 ifi-zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Mar 28 15:06:05 ifi-zen-stream volumio[1190]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Mar 28 15:06:05 ifi-zen-stream volumio[1190]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Mar 28 15:06:05 ifi-zen-stream volumio[1190]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Mar 28 15:06:05 ifi-zen-stream volumio[1190]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Mar 28 15:06:05 ifi-zen-stream volumio[1190]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Mar 28 15:06:05 ifi-zen-stream volumio[1190]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Mar 28 15:06:05 ifi-zen-stream volumio[1190]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 28 15:06:05 ifi-zen-stream volumio[1190]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 28 15:06:07 ifi-zen-stream volumio[1190]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Mar 28 15:06:07 ifi-zen-stream volumio[1190]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Mar 28 15:06:07 ifi-zen-stream volumio[1190]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Mar 28 15:06:07 ifi-zen-stream volumio[1190]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Mar 28 15:06:07 ifi-zen-stream volumio[1190]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Mar 28 15:06:07 ifi-zen-stream volumio[1190]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Mar 28 15:06:07 ifi-zen-stream volumio[1190]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 28 15:06:07 ifi-zen-stream volumio[1190]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 28 15:06:08 ifi-zen-stream play[19107]: 2026-03-28 15:06:08.788 I esdk Sending a per-track periodic update to track-playback. current-position 30314 periodic_update_counter 1 Mar 28 15:06:08 ifi-zen-stream play[19107]: 2026-03-28 15:06:08.788 I esdk State update, position: 30314 previous position: 30314, empty: 0 counter: 1 source: 11 Mar 28 15:06:08 ifi-zen-stream play[19107]: 2026-03-28 15:06:08.788 I esdk Next per-track periodic update will happen in 630268 ms, (requested interval from backend: 600000 ms) Mar 28 15:06:10 ifi-zen-stream play[19107]: 2026-03-28 15:06:10.162 I esdk Got state response Mar 28 15:06:10 ifi-zen-stream play[19107]: 2026-03-28 15:06:10.164 I esdk Notifying kSpPlaybackNotifyMetadataChanged Mar 28 15:06:10 ifi-zen-stream play[19107]: 2026-03-28 15:06:10.164 I app Metadata change: Nothing to update Mar 28 15:06:13 ifi-zen-stream play[19107]: 2026-03-28 15:06:13.413 W esdk SpPumpEvents() is called too slowly: 45505 ms for 100 calls Mar 28 15:06:14 ifi-zen-stream volumio[1190]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Mar 28 15:06:14 ifi-zen-stream volumio[1190]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Mar 28 15:06:14 ifi-zen-stream volumio[1190]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Mar 28 15:06:14 ifi-zen-stream volumio[1190]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Mar 28 15:06:14 ifi-zen-stream volumio[1190]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Mar 28 15:06:14 ifi-zen-stream volumio[1190]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Mar 28 15:06:14 ifi-zen-stream volumio[1190]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 28 15:06:14 ifi-zen-stream volumio[1190]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: ++++--++++ search: {"type":"any","value":"Wilco"} Mar 28 15:06:17 ifi-zen-stream volumio[1190]: Searching all installed plugins Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: , search Mar 28 15:06:17 ifi-zen-stream volumio[1190]: error: Could not retrieve plugin Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 28 15:06:17 ifi-zen-stream volumio[1190]: info: search in webradio selection! Mar 28 15:06:22 ifi-zen-stream volumio[1190]: error: Search in plugin webradio timed out Mar 28 15:06:22 ifi-zen-stream volumio[1190]: info: All search sources collected, pushing search results Mar 28 15:06:28 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:06:28 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: ++++--++++ search: {"type":"any","value":"M"} Mar 28 15:06:32 ifi-zen-stream volumio[1190]: Searching all installed plugins Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: , search Mar 28 15:06:32 ifi-zen-stream volumio[1190]: error: Could not retrieve plugin Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 28 15:06:32 ifi-zen-stream volumio[1190]: info: search in webradio selection! Mar 28 15:06:32 ifi-zen-stream volumio[1190]: (node:1190) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found Mar 28 15:06:32 ifi-zen-stream volumio[1190]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) Mar 28 15:06:32 ifi-zen-stream volumio[1190]: at /volumio/app/plugins/music_service/webradio/index.js:967:31 Mar 28 15:06:32 ifi-zen-stream volumio[1190]: at runMicrotasks () Mar 28 15:06:32 ifi-zen-stream volumio[1190]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Mar 28 15:06:32 ifi-zen-stream volumio[1190]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 28 15:06:32 ifi-zen-stream volumio[1190]: (node:1190) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Mar 28 15:06:32 ifi-zen-stream volumio[1190]: (node:1190) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Mar 28 15:06:34 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:06:35 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:06:37 ifi-zen-stream volumio[1190]: error: Search in plugin webradio timed out Mar 28 15:06:37 ifi-zen-stream volumio[1190]: info: All search sources collected, pushing search results Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: ++++--++++ getUiConfig: {"page":"miscellanea/my_music"} Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 15:06:38 ifi-zen-stream volumio[1190]: error: Could not retrieve plugin music_service streaming_services Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: ++++--++++ getMyCollectionStats: null Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: ++++--++++ getListShares: null Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 28 15:06:38 ifi-zen-stream volumio[1190]: info: ++++--++++ getMyMusicPlugins Mar 28 15:06:40 ifi-zen-stream volumio[1190]: info: ++++--++++ updateDb: null Mar 28 15:06:40 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 28 15:06:40 ifi-zen-stream volumio[1190]: verbose: ControllerMpd::sendMpdCommand update Mar 28 15:06:40 ifi-zen-stream volumio[1190]: info: sendMpdCommand update took 7 milliseconds Mar 28 15:06:42 ifi-zen-stream volumio[1190]: info: ++++--++++ getMyCollectionStats: null Mar 28 15:06:42 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 15:06:46 ifi-zen-stream volumiologrotate[505]: ls: cannot access '/var/log/samba/log.wb-ZEN': No such file or directory Mar 28 15:06:46 ifi-zen-stream volumiologrotate[505]: ls: cannot access 'STREAM': No such file or directory Mar 28 15:06:46 ifi-zen-stream volumio[1190]: info: ++++--++++ getMyCollectionStats: null Mar 28 15:06:46 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 15:06:50 ifi-zen-stream volumio[1190]: info: ++++--++++ getMyCollectionStats: null Mar 28 15:06:50 ifi-zen-stream volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 15:06:50 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:06:51 ifi-zen-stream volumio[1190]: info: ++++--++++ getMenuItems Mar 28 15:06:56 ifi-zen-stream volumio[1190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 15:06:56 ifi-zen-stream volumio[1190]: TypeError: Cannot read property 'slice' of null Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at Object.onceWrapper (events.js:520:26) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at ClientRequest.emit (events.js:400:28) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at TLSSocket.socketOnData (_http_client.js:515:22) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at TLSSocket.emit (events.js:400:28) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at addChunk (internal/streams/readable.js:293:12) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at readableAddChunk (internal/streams/readable.js:267:9) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 28 15:06:56 ifi-zen-stream volumio[1190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 15:06:57 ifi-zen-stream play[19107]: 2026-03-28 15:06:57.795 W audio Bytes were discarded: 1960 at byte_offset: 3123061 Mar 28 15:06:58 ifi-zen-stream sudo[3115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-28 15:05 Mar 28 15:06:58 ifi-zen-stream sudo[3115]: 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:05:02 CST 2025" VOLUMIO_VERSION="3.00.6" VOLUMIO_HARDWARE="zen-stream" VOLUMIO_DEVICENAME="ZEN Stream" VOLUMIO_HASH="3350ed41a04d50b70f39bebb8e49f746"