-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2026-05-31 12:59:51 CEST. -- May 31 12:58:12 ifi-zen-stream volumio[1198]: verbose: New Socket.io Connection to 192.168.1.178 from 192.168.1.11 UA: okhttp/3.12.11 Engine version: 3 Transport: websocket Total Clients: 2 May 31 12:58:12 ifi-zen-stream volumio[1198]: info: ++++--++++ getState May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:58:12 ifi-zen-stream volumio[1198]: info: ++++--++++ getQueue May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetQueue May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreStateMachine::getQueue May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CorePlayQueue::getQueue May 31 12:58:12 ifi-zen-stream volumio[1198]: info: ++++--++++ getBrowseSources: undefined May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:58:12 ifi-zen-stream volumio[1198]: info: ++++--++++ getUiConfig: {"page":"system_controller/system"} May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 12:58:12 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:12 ifi-zen-stream volumio[1198]: info: ++++--++++ browseLibrary: {"uri":"playlists"} May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CURURI: playlists May 31 12:58:12 ifi-zen-stream volumio[1198]: info: Listing playlists May 31 12:58:12 ifi-zen-stream volumio[1198]: info: ++++--++++ getSystemVersion May 31 12:58:12 ifi-zen-stream volumio[1198]: info: Received Get System Version May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:58:12 ifi-zen-stream volumio[1198]: info: Preload queue cleared May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 12:58:12 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 31 12:58:13 ifi-zen-stream volumio[1198]: info: ++++--++++ browseLibrary: {"uri":"favourites"} May 31 12:58:13 ifi-zen-stream volumio[1198]: info: ++++--++++ getState May 31 12:58:13 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:58:13 ifi-zen-stream volumio[1198]: info: Preload queue cleared May 31 12:58:15 ifi-zen-stream volumio[1198]: info: ++++--++++ browseLibrary: {"uri":"favourites"} May 31 12:58:15 ifi-zen-stream volumio[1198]: info: Preload queue cleared May 31 12:58:16 ifi-zen-stream volumio[1198]: info: ++++--++++ browseLibrary: {"uri":"favourites"} May 31 12:58:16 ifi-zen-stream volumio[1198]: info: Preload queue cleared May 31 12:58:18 ifi-zen-stream volumio[1198]: info: ++++--++++ pause May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioPause May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreStateMachine::pause May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreStateMachine::stop May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreStateMachine::stPlaybackTimer May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreStateMachine::updateTrackBlock May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CorePlayQueue::getTrackBlock May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreStateMachine::pushState May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CorePlayQueue::getTrack 0 May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioPushState May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreStateMachine::serviceStop May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CorePlayQueue::getTrack 0 May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::serviceStop May 31 12:58:18 ifi-zen-stream volumio[1198]: info: [1780225098137] ControllerWebradio::stop May 31 12:58:18 ifi-zen-stream volumio[1198]: verbose: ControllerMpd::sendMpdCommand stop May 31 12:58:18 ifi-zen-stream volumio[1198]: info: May 31 12:58:18 ifi-zen-stream volumio[1198]: ---------------------------- MPD announces state update: player May 31 12:58:18 ifi-zen-stream volumio[1198]: info: sendMpdCommand stop took 57 milliseconds May 31 12:58:18 ifi-zen-stream volumio[1198]: info: ControllerMpd::getState May 31 12:58:18 ifi-zen-stream volumio[1198]: verbose: ControllerMpd::sendMpdCommand status May 31 12:58:18 ifi-zen-stream volumio[1198]: info: sendMpdCommand status took 2 milliseconds May 31 12:58:18 ifi-zen-stream volumio[1198]: verbose: ControllerMpd::parseState May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CorePlayQueue::getTrack 0 May 31 12:58:18 ifi-zen-stream volumio[1198]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 12:58:18 ifi-zen-stream volumio[1198]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 12:58:18 ifi-zen-stream volumio[1198]: verbose: ControllerMpd::parseTrackInfo May 31 12:58:18 ifi-zen-stream volumio[1198]: info: ControllerMpd::pushState May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::servicePushState May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreStateMachine::pushState May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CorePlayQueue::getTrack 0 May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioPushState May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:58:18 ifi-zen-stream volumio[1198]: info: CorePlayQueue::getTrack 0 May 31 12:58:18 ifi-zen-stream volumio[1198]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 12:58:18 ifi-zen-stream volumio[1198]: info: ------------------------------ 28ms May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] TRACE WebsocketClient: [0xab827830]: Binary data received, size: 40 May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] INFO ProtocolHandler: [0xab821348]: Received SetState message: May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] INFO ProtocolHandler: [0xab821348]: Playing state: Playing May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] INFO ProtocolHandler: [0xab821348]: Playback position: May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] INFO ProtocolHandler: [0xab821348]: Queue version: May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] INFO ProtocolHandler: [0xab821348]: Current track: May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] INFO ProtocolHandler: [0xab821348]: Next track: May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] INFO MediaEngine: [0xab820c08]: Resuming playback May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] DEBUG MediaEngineCommandSequencer: [0xab820f80]: Enqueuing command: Resume May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Executing command: Resume May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] INFO AudioRenderer: [0xab820e28]: Resuming audio renderer May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] TRACE AudioRenderer: [0xab820e28]: State changed: Paused -> Resuming May 31 12:58:29 ifi-zen-stream play[1718]: 20260531 12:58:29.662 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Audio renderer state changed: Paused -> Resuming May 31 12:58:29 ifi-zen-stream volumio[1198]: info: ConvSwitch: Get message: request:pause May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioStop May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreStateMachine::stop May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioStop May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreStateMachine::stop May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:58:29 ifi-zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... May 31 12:58:29 ifi-zen-stream volumio[1198]: info: May 31 12:58:29 ifi-zen-stream volumio[1198]: ---------------------------- Client requests Start Qobuz PlaybackRoutine May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuz_connect , startQobuzPlayback May 31 12:58:29 ifi-zen-stream volumio[1198]: info: Qobuz Connect playback start May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioStop May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreStateMachine::stop May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:58:29 ifi-zen-stream volumio[1198]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:58:29 ifi-zen-stream volumio[1198]: info: MetaClient: Sent Meta May 31 12:58:29 ifi-zen-stream volumio[1198]: info: MetaClient: Get message: success May 31 12:58:29 ifi-zen-stream systemd[1]: ifi-system-internet.service: Succeeded. May 31 12:58:29 ifi-zen-stream systemd[1]: Started Internet Detect for iFi Streamer. May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.672 [1738.1738] TRACE SampleApp: ALSA PCM's descriptor count: 1 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.673 [1738.1738] INFO SampleApp: Resuming playback May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.676 [1738.1738] DEBUG AudioRenderer: [0xab820e28]: Transition to playing state (resume) has been acknowledged May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.676 [1738.1738] TRACE AudioRenderer: [0xab820e28]: State changed: Resuming -> Playing May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.676 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Audio renderer state changed: Resuming -> Playing May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.676 [1738.1738] TRACE MediaEngine: [0xab820c08]: State changed: Paused -> Playing May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] DEBUG MediaEngineCommandSequencer: [0xab820f80]: Confirming command: Resume May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] DEBUG PlaybackProgressManager: [0xab820e80]: Audio playback started, time until playback gets near the end: 63404ms May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] INFO SampleApp: Playback state changed: Playing May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 117702 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] INFO StreamingEventReporter: [0xab821760]: Reporting streaming started event May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Track ID: 30369012 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Start position: 117702ms May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Context UUID: 637543d9-94da-4bbc-9e22-5091436cf044 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Blob ID: 100001.ctwf9okZE3YQTJwFmP8BLk4-kXJb98sJ2nkHssJese3faL6xrT2nh-YfJsFrb-qK.QL4XNJhD7dZwsTsMXMncvfarko8 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] DEBUG StreamingEventReporter: [0xab821760]: Sending streaming reports May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] INFO HttpDownloader: [0xab8217b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 117702 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:58:30 ifi-zen-stream node[496]: in qobuz-connect May 31 12:58:30 ifi-zen-stream node[496]: message: {"title":"Il cielo in una stanza","artist":"Franco Battiato","album":"Fleurs 3","albumart":"https://static.qobuz.com/images/covers/56/44/0886445714456_600.jpg","duration":"191","seek":"0"} May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.677 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 117702 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 191106 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 6 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: 7 May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.678 [1738.1738] DEBUG UserInactivityManager: [0xab8219a0]: Renderer is actively playing May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.683 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.684 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.684 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.733 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 117702ms May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.734 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 117702ms May 31 12:58:30 ifi-zen-stream play[1718]: 20260531 12:58:30.735 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 117702ms May 31 12:58:31 ifi-zen-stream play[1718]: 20260531 12:58:31.001 [1738.1738] DEBUG StreamingEventReporter: [0xab821760]: Streaming report submitted successfully May 31 12:58:31 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::servicePushState May 31 12:58:31 ifi-zen-stream volumio[1198]: info: CoreStateMachine::pushState May 31 12:58:31 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:58:31 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioPushState May 31 12:58:31 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:58:32 ifi-zen-stream volumio[1198]: info: MetaClient: Sent Meta May 31 12:58:32 ifi-zen-stream volumio[1198]: info: MetaClient: Get message: success May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 127708 May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 127708 May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 191106 May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 6 May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: 7 May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.677 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:58:40 ifi-zen-stream play[1718]: 20260531 12:58:40.678 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 127708ms May 31 12:58:41 ifi-zen-stream volumiologrotate[520]: ls: cannot access '/var/log/samba/log.wb-ZEN': No such file or directory May 31 12:58:41 ifi-zen-stream volumiologrotate[520]: ls: cannot access 'STREAM': No such file or directory May 31 12:58:42 ifi-zen-stream sudo[28458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:58:42 ifi-zen-stream sudo[28458]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:58:42 ifi-zen-stream sudo[28458]: pam_unix(sudo:session): session closed for user root May 31 12:58:42 ifi-zen-stream sudo[28460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:58:42 ifi-zen-stream sudo[28460]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:58:42 ifi-zen-stream sudo[28460]: pam_unix(sudo:session): session closed for user root May 31 12:58:42 ifi-zen-stream volumio[1198]: verbose: New Socket.io Connection to 192.168.1.178 from 192.168.1.11 UA: Mozilla/5.0 (Linux; Android 12; SM-T500 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getState May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getMultiRoomDevices: null May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getMultiroom: null May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 12:58:43 ifi-zen-stream volumio[1198]: error: Could not retrieve plugin audio_interface multiroom May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getAudioOutputs: null May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getBrowseSources: null May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ listPlaylist: null May 31 12:58:43 ifi-zen-stream volumio[1198]: info: Listing playlists May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getUiSettings May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getWizard May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getPrivacySettings May 31 12:58:43 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:58:43 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:44 ifi-zen-stream volumio[1198]: info: ++++--++++ getMyVolumioStatus May 31 12:58:44 ifi-zen-stream volumio[1198]: info: ++++--++++ getSystemInfo May 31 12:58:44 ifi-zen-stream volumio[1198]: info: Received Get System Info May 31 12:58:44 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:58:44 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:58:44 ifi-zen-stream volumio[1198]: info: Discovery: Getting this device information May 31 12:58:44 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:58:44 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:58:44 ifi-zen-stream volumio[1198]: info: ++++--++++ getIfiUser: null May 31 12:58:45 ifi-zen-stream sudo[28478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:58:45 ifi-zen-stream sudo[28478]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:58:45 ifi-zen-stream sudo[28479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:58:45 ifi-zen-stream sudo[28479]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:58:45 ifi-zen-stream sudo[28478]: pam_unix(sudo:session): session closed for user root May 31 12:58:45 ifi-zen-stream sudo[28479]: pam_unix(sudo:session): session closed for user root May 31 12:58:45 ifi-zen-stream sudo[28486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:58:45 ifi-zen-stream sudo[28486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:58:45 ifi-zen-stream sudo[28486]: pam_unix(sudo:session): session closed for user root May 31 12:58:45 ifi-zen-stream volumio[1198]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.11 UA: Mozilla/5.0 (Linux; Android 12; SM-T500 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 31 12:58:45 ifi-zen-stream sudo[28487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:58:45 ifi-zen-stream sudo[28487]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:58:45 ifi-zen-stream sudo[28487]: pam_unix(sudo:session): session closed for user root May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getState May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:58:45 ifi-zen-stream volumio[1198]: verbose: New Socket.io Connection to 192.168.1.178 from 192.168.1.11 UA: Mozilla/5.0 (Linux; Android 12; SM-T500 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMultiRoomDevices: null May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMultiroom: null May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 12:58:45 ifi-zen-stream volumio[1198]: error: Could not retrieve plugin audio_interface multiroom May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getBrowseSources: null May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ listPlaylist: null May 31 12:58:45 ifi-zen-stream volumio[1198]: info: Listing playlists May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getState May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMultiRoomDevices: null May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMultiroom: null May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 12:58:45 ifi-zen-stream volumio[1198]: error: Could not retrieve plugin audio_interface multiroom May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getBrowseSources: null May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:58:45 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ listPlaylist: null May 31 12:58:45 ifi-zen-stream volumio[1198]: info: Listing playlists May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:45 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:46 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.677 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 137715 May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.679 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.680 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.681 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.681 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 137715 May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.682 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 191106 May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.682 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.683 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 6 May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.683 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: 7 May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.684 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.684 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.685 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:58:50 ifi-zen-stream play[1718]: 20260531 12:58:50.686 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 137715ms May 31 12:58:57 ifi-zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... May 31 12:58:58 ifi-zen-stream systemd[1]: ifi-system-internet.service: Succeeded. May 31 12:58:58 ifi-zen-stream systemd[1]: Started Internet Detect for iFi Streamer. May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 147721 May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 147721 May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 191106 May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 6 May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: 7 May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.678 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:00 ifi-zen-stream play[1718]: 20260531 12:59:00.679 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 147721ms May 31 12:59:01 ifi-zen-stream volumio[1198]: info: ++++--++++ getPrivacySettings May 31 12:59:01 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:59:01 ifi-zen-stream volumio[1198]: info: ++++--++++ callMethod: {"type":"plugin","endpoint":"system_controller/my_volumio","method":"retreiveBackendEventStates"} May 31 12:59:01 ifi-zen-stream volumio[1198]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 12:59:01 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 12:59:01 ifi-zen-stream volumio[1198]: error: Could not retrieve plugin system_controller my_volumio May 31 12:59:01 ifi-zen-stream volumio[1198]: info: ++++--++++ getSystemVersion May 31 12:59:01 ifi-zen-stream volumio[1198]: info: Received Get System Version May 31 12:59:01 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:59:01 ifi-zen-stream volumio[1198]: info: ++++--++++ getSystemInfo May 31 12:59:01 ifi-zen-stream volumio[1198]: info: Received Get System Info May 31 12:59:01 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:59:01 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:59:01 ifi-zen-stream volumio[1198]: info: Discovery: Getting this device information May 31 12:59:01 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:59:01 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.677 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 157727 May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.679 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.680 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 157727 May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 191106 May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 6 May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: 7 May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.682 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.682 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.682 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:10 ifi-zen-stream play[1718]: 20260531 12:59:10.682 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 157727ms May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 167734 May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 167734 May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 191106 May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 6 May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: 7 May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.678 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:20 ifi-zen-stream play[1718]: 20260531 12:59:20.679 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 167734ms May 31 12:59:20 ifi-zen-stream volumio[1198]: info: ++++--++++ iFiLogin: {"value":{"username":"Tanograzia3642","password":"Tanuzzu3642!"}} May 31 12:59:20 ifi-zen-stream volumio[1198]: config not exist, create ... May 31 12:59:21 ifi-zen-stream volumio[1198]: useLogin url:http://api.nexis.ifi-cloud.com/api/auth/passport May 31 12:59:21 ifi-zen-stream volumio[1198]: useLogin response data: May 31 12:59:21 ifi-zen-stream volumio[1198]: { May 31 12:59:21 ifi-zen-stream volumio[1198]: code: '200', May 31 12:59:21 ifi-zen-stream volumio[1198]: data: { May 31 12:59:21 ifi-zen-stream volumio[1198]: a_username: 'Tanograzia3642', May 31 12:59:21 ifi-zen-stream volumio[1198]: token: 'eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJhdWQiOiJBNTY0NzczNzAzNTI1NDE2OTYiLCJsb2dpblR5cGUiOiJBUFBTIiwiZXhwIjoxNzgyODE3MTYxfQ.z2lPKJTDnLRBdcnK3-u0j11YM5lHmL45bEKvMQFp-90', May 31 12:59:21 ifi-zen-stream volumio[1198]: timezone: 'Asia/Shanghai', May 31 12:59:21 ifi-zen-stream volumio[1198]: expired_time: '2026-06-30 18:59:21', May 31 12:59:21 ifi-zen-stream volumio[1198]: ip_address: 'null', May 31 12:59:21 ifi-zen-stream volumio[1198]: cookie: { name: 'JSESSIONID', value: '6151B8BBB400E65A43785270715641F5' } May 31 12:59:21 ifi-zen-stream volumio[1198]: } May 31 12:59:21 ifi-zen-stream volumio[1198]: } May 31 12:59:21 ifi-zen-stream volumio[1198]: user login success May 31 12:59:21 ifi-zen-stream sudo[28882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:59:21 ifi-zen-stream sudo[28882]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:59:21 ifi-zen-stream sudo[28882]: pam_unix(sudo:session): session closed for user root May 31 12:59:21 ifi-zen-stream sudo[28883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:59:21 ifi-zen-stream sudo[28883]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:59:22 ifi-zen-stream sudo[28883]: pam_unix(sudo:session): session closed for user root May 31 12:59:22 ifi-zen-stream volumio[1198]: verbose: New Socket.io Connection to 192.168.1.178 from 192.168.1.11 UA: Mozilla/5.0 (Linux; Android 12; SM-T500 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getState May 31 12:59:22 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetState May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getMultiRoomDevices: null May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getMultiroom: null May 31 12:59:22 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 12:59:22 ifi-zen-stream volumio[1198]: error: Could not retrieve plugin audio_interface multiroom May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getBrowseSources: null May 31 12:59:22 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:59:22 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:59:22 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ listPlaylist: null May 31 12:59:22 ifi-zen-stream volumio[1198]: info: Listing playlists May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getQueue May 31 12:59:22 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioGetQueue May 31 12:59:22 ifi-zen-stream volumio[1198]: info: CoreStateMachine::getQueue May 31 12:59:22 ifi-zen-stream volumio[1198]: info: CorePlayQueue::getQueue May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:59:22 ifi-zen-stream volumio[1198]: info: ++++--++++ getMenuItems May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.678 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 177741 May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.678 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.678 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 177741 May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 191106 May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.678 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.679 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 6 May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.679 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: 7 May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.679 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.679 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.679 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:30 ifi-zen-stream play[1718]: 20260531 12:59:30.679 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 177741ms May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.081 [1738.1738] DEBUG AudioRenderer: [0xab820e28]: Playback is nearly finished May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.081 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Playback is nearly finished May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.081 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: [Stream 15]: Running audio stream May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.081 [1738.1738] INFO UrlAudioSource: [0xabbac250]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3675883&eid=30369013&fmt=6&profile=raw&app_id=239488056&cid=2624441&etsp=1780227742&hmac=K3GKg44x0GdHQaHobSZ5Spk99t4 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.081 [1738.1738] TRACE AudioSource: [0xabbac250]: State changed: Idle -> Running May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.081 [1738.1738] DEBUG Ringbuffer: [0xabe07990]: Creating Audio Ringbuffer May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.081 [1738.1738] INFO ContentFetcher: [0xab9cc4f8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3675883&eid=30369013&fmt=6&profile=raw&app_id=239488056&cid=2624441&etsp=1780227742&hmac=K3GKg44x0GdHQaHobSZ5Spk99t4, offset: 0 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.082 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Next stream changed state: Info Retrieved -> Running May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.082 [1738.1738] INFO SampleApp: [Stream 15]: New audio stream (starting from 0ms) May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.082 [1738.1738] INFO SampleApp: [Stream 15]: Stream metadata became available: May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.082 [1738.1738] INFO SampleApp: Title: Le tue radici May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.082 [1738.1738] INFO SampleApp: Artist: Franco Battiato May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.082 [1738.1738] INFO SampleApp: Album: Fleurs 3 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.082 [1738.1738] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/56/44/0886445714456_600.jpg May 31 12:59:34 ifi-zen-stream node[496]: in qobuz-connect May 31 12:59:34 ifi-zen-stream node[496]: message: {"title":"Le tue radici","artist":"Franco Battiato","album":"Fleurs 3","albumart":"https://static.qobuz.com/images/covers/56/44/0886445714456_600.jpg","duration":"200","seek":"0"} May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.424 [1738.1738] INFO UrlAudioSource: [0xabbac250]: Content info extracted May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.424 [1738.1738] INFO UrlAudioSource: [0xabbac250]: Size: 23987288 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.447 [1738.28996] INFO AudioDecoder: [0xab8b1598]: Stream info: May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.447 [1738.28996] INFO AudioDecoder: [0xab8b1598]: Total samples: 8843520 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.447 [1738.28996] INFO AudioDecoder: [0xab8b1598]: Sample rate: 44100 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.447 [1738.28996] INFO AudioDecoder: [0xab8b1598]: Channel count: 2 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.447 [1738.28996] INFO AudioDecoder: [0xab8b1598]: Bits per sample: 16 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.447 [1738.28996] INFO AudioDecoder: [0xab8b1598]: Duration: 200533 May 31 12:59:34 ifi-zen-stream play[1718]: 20260531 12:59:34.448 [1738.1738] INFO UrlAudioSource: [0xabbac250]: Audio properties have changed May 31 12:59:38 ifi-zen-stream play[1718]: 20260531 12:59:38.105 [1738.1738] TRACE AudioSource: [0xab8baf80]: State changed: Running -> Finished May 31 12:59:38 ifi-zen-stream play[1718]: 20260531 12:59:38.105 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: [Stream 14]: Audio stream has finished May 31 12:59:38 ifi-zen-stream play[1718]: 20260531 12:59:38.105 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Current stream changed state: Running -> Finished May 31 12:59:38 ifi-zen-stream play[1718]: 20260531 12:59:38.105 [1738.1738] INFO SampleApp: [Stream 14]: Audio stream finished May 31 12:59:38 ifi-zen-stream play[1718]: 20260531 12:59:38.105 [1738.1738] DEBUG SampleApp: Scheduling playback nearly finished timer: 5434ms May 31 12:59:38 ifi-zen-stream play[1718]: 20260531 12:59:38.105 [1738.1738] DEBUG SampleApp: Scheduling playback finished timer for: 5934ms May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 187749 May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 187749 May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 191106 May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 6 May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: 7 May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.680 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:40 ifi-zen-stream play[1718]: 20260531 12:59:40.681 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 187749ms May 31 12:59:41 ifi-zen-stream volumiologrotate[520]: ls: cannot access '/var/log/samba/log.wb-ZEN': No such file or directory May 31 12:59:41 ifi-zen-stream volumiologrotate[520]: ls: cannot access 'STREAM': No such file or directory May 31 12:59:43 ifi-zen-stream play[1718]: 20260531 12:59:43.542 [1738.1738] INFO SampleApp: Playback is nearly finished May 31 12:59:43 ifi-zen-stream play[1718]: 20260531 12:59:43.542 [1738.1738] DEBUG SampleApp: Starting audio data flow from the next audio source to allow for gapless track transition May 31 12:59:43 ifi-zen-stream node[496]: in qobuz-connect May 31 12:59:43 ifi-zen-stream node[496]: message: {"title":"Le tue radici","artist":"Franco Battiato","album":"Fleurs 3","albumart":"https://static.qobuz.com/images/covers/56/44/0886445714456_600.jpg","duration":"200","seek":"0"} May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.039 [1738.1738] INFO SampleApp: Playback has finished May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG AudioRenderer: [0xab820e28]: Playback has finished (last track: 0) May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG PlaybackProgressManager: [0xab820e80]: Audio playback is stopped May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] TRACE AudioRenderer: [0xab820e28]: State changed: Playing -> Track Finished May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG AudioRenderer: [0xab820e28]: Playback initiated (after track finished playing) May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] TRACE AudioRenderer: [0xab820e28]: State changed: Track Finished -> Playing May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Audio renderer state changed: Playing -> Track Finished May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: [Stream 14]: Disposing of audio stream May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] INFO ContentFetcher: [0xabd93048]: Canceling download May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG Ringbuffer: [0xab89f278]: Freeing Audio Ringbuffer May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG AudioStreamInfoFetcher: [0xabb19d98]: Freeing Audio Stream Info Fetcher May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG MetadataFetcher: [0xab825608]: Freeing Metadata Fetcher May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG PlaybackUrlFetcher: [0xab85bae8]: Freeing Playback URL Fetcher May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] TRACE MediaEngine: [0xab820c08]: State changed: Playing -> Transitioning May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Audio renderer state changed: Track Finished -> Playing May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] TRACE MediaEngine: [0xab820c08]: State changed: Transitioning -> Playing May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Current stream changed state: Finished -> Nonexistent May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.040 [1738.1738] INFO SampleApp: [Stream 14]: Audio stream has been disposed of May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] INFO StreamingEventReporter: [0xab821760]: Reporting streaming ended event May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: End position: 191106ms May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Duration: 73404ms May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] DEBUG StreamingEventReporter: [0xab821760]: Sending streaming reports May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] INFO HttpDownloader: [0xab8217b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 6 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 6 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 200533 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 7 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: -2 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.041 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] DEBUG PlaybackProgressManager: [0xab820e80]: Audio playback started, time until playback gets near the end: 190525ms May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 8 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 8 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 200533 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 7 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: -2 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending FileAudioQualityChanged message May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Sample rate: 44100 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Bit depth: 16 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Channel count: 2 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.043 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Audio quality: CD May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 9 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] INFO StreamingEventReporter: [0xab821760]: Reporting streaming started event May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Track ID: 30369013 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Start position: 9ms May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Context UUID: 637543d9-94da-4bbc-9e22-5091436cf044 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE StreamingEventReporter: [0xab821760]: Blob ID: 100001.Md7xb-ziybuJxsTXMIdwjKUvJAQ0hMqu_IMQSxt8wovJZtZ6tBoHRpnWmbd7e56T.Bz6FAtMTH-K38oAs_HEW2Wjsg1Y May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE AudioRenderer: [0xab820e28]: Playback position: 9 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] INFO ProtocolHandler: [0xab821348]: Need to send state update May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] INFO QConnectMessageSender: [0xab9abf30]: Sending StateUpdated message May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Playback state: Playing May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current position: 9 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Duration: 200533 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Queue version: 7.1 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Current queue item ID: 7 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QConnectMessageSender: [0xab9abf30]: Next queue item ID: -2 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] INFO QwspMessageSender: [0xab939e98]: Sending Payload message May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.044 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Protocol: QConnect May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.045 [1738.1738] TRACE QwspMessageSender: [0xab939e98]: Channel: Backend May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.045 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 6ms May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.045 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 8ms May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.045 [1738.1738] TRACE PlaybackPositionEstimator: [0xab8218d8]: Synced playback position: 9ms May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] TRACE WebsocketClient: [0xab827830]: Binary data received, size: 71 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] TRACE WebsocketClient: [0xab827830]: Binary data received, size: 71 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO ProtocolHandler: [0xab821348]: Received SetState message: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO ProtocolHandler: [0xab821348]: Playing state: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO ProtocolHandler: [0xab821348]: Playback position: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO ProtocolHandler: [0xab821348]: Queue version: 7.1 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO ProtocolHandler: [0xab821348]: Current track: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO ProtocolHandler: [0xab821348]: Next track: TID: 30369014, QID: 8, Context UUID: 637543d9-94da-4bbc-9e22-5091436cf044 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] DEBUG MediaEngineCommandSequencer: [0xab820f80]: Enqueuing command: SetNextTrack May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Executing command: SetNextTrack May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO MediaEngine: [0xab820c08]: Setting next track: 30369014 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: New stream: 16 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] DEBUG PlaybackUrlFetcher: [0xab85bae8]: Creating Playback URL Fetcher for track: 30369014 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO HttpDownloader: [0xabb1a188]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6b3f3c16c10970fdbe5440216fea1510&request_ts=1780225184&track_id=30369014 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] DEBUG MetadataFetcher: [0xaba53ab0]: Creating Metadata Fetcher for track: 30369014 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.112 [1738.1738] INFO HttpDownloader: [0xab827528]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=30369014 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] DEBUG AudioStreamInfoFetcher: [0xabbc4010]: Creating Audio Stream Info Fetcher for track: 30369014 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] INFO HttpDownloader: [0xabb1aaf0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=45a6af802b019ed2e217cf3423138d1d&request_ts=1780225184&track_id=30369014 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] DEBUG MediaEngineCommandSequencer: [0xab820f80]: Confirming command: SetNextTrack May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] INFO ProtocolHandler: [0xab821348]: Received SetState message: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] INFO ProtocolHandler: [0xab821348]: Playing state: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] INFO ProtocolHandler: [0xab821348]: Playback position: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] INFO ProtocolHandler: [0xab821348]: Queue version: 7.1 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] INFO ProtocolHandler: [0xab821348]: Current track: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] INFO ProtocolHandler: [0xab821348]: Next track: TID: 30369014, QID: 8, Context UUID: 637543d9-94da-4bbc-9e22-5091436cf044 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.113 [1738.1738] DEBUG ProtocolHandler: [0xab821348]: Not updating next track because they are the same May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.115 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Next stream changed state: Nonexistent -> Created May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.116 [1738.1738] TRACE WebsocketClient: [0xab827830]: Binary data received, size: 71 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.116 [1738.1738] INFO ProtocolHandler: [0xab821348]: Received SetState message: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.116 [1738.1738] INFO ProtocolHandler: [0xab821348]: Playing state: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.116 [1738.1738] INFO ProtocolHandler: [0xab821348]: Playback position: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.116 [1738.1738] INFO ProtocolHandler: [0xab821348]: Queue version: 7.1 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.116 [1738.1738] INFO ProtocolHandler: [0xab821348]: Current track: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.116 [1738.1738] INFO ProtocolHandler: [0xab821348]: Next track: TID: 30369014, QID: 8, Context UUID: 637543d9-94da-4bbc-9e22-5091436cf044 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.116 [1738.1738] DEBUG ProtocolHandler: [0xab821348]: Not updating next track because they are the same May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.255 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: [Stream 16]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3675883&eid=30369014&fmt=6&profile=raw&app_id=239488056&cid=2624441&etsp=1780228784&hmac=1ehQLaZd5wwRx6Qo7ulXjKTU2K0 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.273 [1738.1738] DEBUG StreamingEventReporter: [0xab821760]: Streaming report submitted successfully May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.273 [1738.1738] DEBUG StreamingEventReporter: [0xab821760]: Sending streaming reports May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.273 [1738.1738] INFO HttpDownloader: [0xab8217b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.283 [1738.1738] TRACE MetadataFetcher: [0xaba53ab0]: Track 30369014 doesn't have a valid version field May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.283 [1738.1738] TRACE MetadataFetcher: [0xaba53ab0]: Track 30369014 doesn't have a valid work field May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.283 [1738.1738] TRACE MetadataFetcher: [0xaba53ab0]: Track 30369014 doesn't have a valid album version field May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.283 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: [Stream 16]: Metadata became available: May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.283 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: Title: Se tu sapessi May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.283 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: Artist: Franco Battiato May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.283 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: Album: Fleurs 3 May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.283 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: Album art URL: https://static.qobuz.com/images/covers/56/44/0886445714456_600.jpg May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.316 [1738.1738] INFO AudioStreamManager: [0xab820cf0]: [Stream 16]: stream information have been fetched May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.316 [1738.1738] DEBUG MediaEngine: [0xab820c08]: Next stream changed state: Created -> Info Retrieved May 31 12:59:44 ifi-zen-stream play[1718]: 20260531 12:59:44.364 [1738.1738] DEBUG StreamingEventReporter: [0xab821760]: Streaming report submitted successfully May 31 12:59:50 ifi-zen-stream volumio[1198]: info: ++++--++++ removeFromQueue: {"value":0} May 31 12:59:50 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::volumioRemoveQueueItem May 31 12:59:50 ifi-zen-stream volumio[1198]: info: CoreStateMachine::removeQueueItem May 31 12:59:50 ifi-zen-stream volumio[1198]: info: CoreStateMachine::stop May 31 12:59:50 ifi-zen-stream volumio[1198]: info: CoreStateMachine::serviceStop May 31 12:59:50 ifi-zen-stream volumio[1198]: info: CoreCommandRouter::serviceStop May 31 12:59:50 ifi-zen-stream volumio[1198]: error: Could not retrieve plugin music_service qobuz_connect May 31 12:59:50 ifi-zen-stream volumio[1198]: error: WARNING: No stop method for service qobuz_connect May 31 12:59:50 ifi-zen-stream volumio[1198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 12:59:50 ifi-zen-stream volumio[1198]: TypeError: Cannot read property 'then' of undefined May 31 12:59:50 ifi-zen-stream volumio[1198]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1378:7) May 31 12:59:50 ifi-zen-stream volumio[1198]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:167:28) May 31 12:59:50 ifi-zen-stream volumio[1198]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:189:33) May 31 12:59:50 ifi-zen-stream volumio[1198]: at Socket.emit (events.js:400:28) May 31 12:59:50 ifi-zen-stream volumio[1198]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 31 12:59:50 ifi-zen-stream volumio[1198]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 31 12:59:50 ifi-zen-stream volumio[1198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 12:59:51 ifi-zen-stream sudo[29203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 12:58 May 31 12:59:51 ifi-zen-stream sudo[29203]: 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"