-- Logs begin at Wed 2022-07-13 14:55:24 UTC, end at Tue 2025-12-23 06:49:59 UTC. -- Dec 23 06:48:26 musical-fidelity systemd[1]: Starting Cleanup of Temporary Directories... Dec 23 06:48:26 musical-fidelity volumio[7436]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 23 06:48:26 musical-fidelity systemd[1]: Started Cleanup of Temporary Directories. Dec 23 06:48:26 musical-fidelity volumio[7436]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Dec 23 06:48:26 musical-fidelity volumio[7436]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Dec 23 06:48:34 musical-fidelity hostapd[1088]: wlan0: STA c2:9c:6e:85:b3:2c IEEE 802.11: associated Dec 23 06:48:34 musical-fidelity hostapd[1088]: wlan0: STA c2:9c:6e:85:b3:2c RADIUS: starting accounting session 62CEDCEF-00000000 Dec 23 06:48:35 musical-fidelity dhcpd[1062]: DHCPDISCOVER from c2:9c:6e:85:b3:2c via wlan0 Dec 23 06:48:36 musical-fidelity dhcpd[1062]: DHCPOFFER on 192.168.211.33 to c2:9c:6e:85:b3:2c (CET-AL00) via wlan0 Dec 23 06:48:36 musical-fidelity dhcpd[1062]: Wrote 99 leases to leases file. Dec 23 06:48:36 musical-fidelity dhcpd[1062]: DHCPREQUEST for 192.168.211.33 (192.168.211.1) from c2:9c:6e:85:b3:2c (CET-AL00) via wlan0 Dec 23 06:48:36 musical-fidelity dhcpd[1062]: DHCPACK on 192.168.211.33 to c2:9c:6e:85:b3:2c (CET-AL00) via wlan0 Dec 23 06:48:36 musical-fidelity kernel: RTL871X: ap recv deauth reason code(3) sta:c2:9c:6e:85:b3:2c Dec 23 06:48:36 musical-fidelity hostapd[1088]: wlan0: STA c2:9c:6e:85:b3:2c IEEE 802.11: disassociated Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Clearing queue after UPNP request Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::ClearQueue Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::stop Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 06:48:38 musical-fidelity volumio[7436]: verbose: UNSET VOLATILE Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::stPlaybackTimer Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::updateTrackBlock Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrackBlock Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::serviceStop Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:48:38 musical-fidelity volumio[7436]: info: ControllerMpd::stop Dec 23 06:48:38 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CorePlayQueue::clearPlayQueue Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CorePlayQueue::saveQueue Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushQueue Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Dec 23 06:48:38 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Dec 23 06:48:38 musical-fidelity volumio[7436]: ---------------------------- MPD announces state update: player Dec 23 06:48:38 musical-fidelity volumio[7436]: info: ControllerMpd::getState Dec 23 06:48:38 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand status Dec 23 06:48:38 musical-fidelity volumio[7436]: info: sendMpdCommand stop took 114 milliseconds Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Starting UPNP Playback Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Preparing playback through UPNP Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioGetState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 06:48:38 musical-fidelity volumio[7436]: error: updateQueue error: null Dec 23 06:48:38 musical-fidelity volumio[7436]: info: ------------------------------ 13ms Dec 23 06:48:38 musical-fidelity volumio[7436]: info: sendMpdCommand status took 12 milliseconds Dec 23 06:48:38 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: ControllerMpd::pushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::servicePushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:48:38 musical-fidelity volumio[7436]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 06:48:38 musical-fidelity volumio[7436]: verbose: CURRENT POSITION 0 Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState stateService stop Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState currentStatus stop Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:48:38 musical-fidelity volumio[7436]: info: No code Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:48:38 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:48:38 musical-fidelity volumio[7436]: info: ------------------------------ 38ms Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:48:38 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:48:39 musical-fidelity volumio[7436]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E4%BD%B3/%E5%8F%88%E8%A7%81%E9%82%93%E4%B8%BD%E5%90%9B/d302659a-1ec1-4e82-8969-755911f97b45.jpg' Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Dec 23 06:48:39 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: ------------------------------ 4ms Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Dec 23 06:48:39 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: ------------------------------ 3ms Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Dec 23 06:48:39 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: ------------------------------ 3ms Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Dec 23 06:48:39 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Dec 23 06:48:39 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: ------------------------------ 4ms Dec 23 06:48:39 musical-fidelity volumio[7436]: info: ------------------------------ 3ms Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Dec 23 06:48:39 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:48:39 musical-fidelity volumio[7436]: info: ------------------------------ 3ms Dec 23 06:48:41 musical-fidelity volumio[7436]: info: Dec 23 06:48:41 musical-fidelity volumio[7436]: ---------------------------- MPD announces state update: player Dec 23 06:48:41 musical-fidelity volumio[7436]: info: ControllerMpd::getState Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand status Dec 23 06:48:41 musical-fidelity volumio[7436]: info: Dec 23 06:48:41 musical-fidelity volumio[7436]: ---------------------------- MPD announces state update: player Dec 23 06:48:41 musical-fidelity volumio[7436]: info: sendMpdCommand status took 4 milliseconds Dec 23 06:48:41 musical-fidelity volumio[7436]: info: ControllerMpd::getState Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand status Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseState Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 06:48:41 musical-fidelity volumio[7436]: info: sendMpdCommand status took 3 milliseconds Dec 23 06:48:41 musical-fidelity volumio[7436]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseState Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseTrackInfo Dec 23 06:48:41 musical-fidelity volumio[7436]: info: ControllerMpd::pushState Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::servicePushState Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: In UPNP mode Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"674 Kbps","isStreaming":false,"title":"玻璃窗的爱","artist":"童丽","album":"胆色2","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=356867918&fmt=6&profile=raw&app_id=195822208&cid=3599692&etsp=1766476118&hmac=MZYFSZdJUiqL0Bdl3CCb_HWpwl8","trackType":"qobuz"} Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: CURRENT POSITION 0 Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState stateService play Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState currentStatus stop Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:48:41 musical-fidelity volumio[7436]: info: ------------------------------ 25ms Dec 23 06:48:41 musical-fidelity volumio[7436]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseTrackInfo Dec 23 06:48:41 musical-fidelity volumio[7436]: info: ControllerMpd::pushState Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::servicePushState Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: In UPNP mode Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"674 Kbps","isStreaming":false,"title":"玻璃窗的爱","artist":"童丽","album":"胆色2","uri":"http://streaming-qobuz-std.akamaized.net/file?uid=8546148&eid=356867918&fmt=6&profile=raw&app_id=195822208&cid=3599692&etsp=1766476118&hmac=MZYFSZdJUiqL0Bdl3CCb_HWpwl8","trackType":"qobuz"} Dec 23 06:48:41 musical-fidelity volumio[7436]: verbose: CURRENT POSITION 0 Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState stateService play Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState currentStatus play Dec 23 06:48:41 musical-fidelity volumio[7436]: info: Received an update from plugin. extracting info from payload Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:48:41 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:48:41 musical-fidelity volumio[7436]: info: ------------------------------ 48ms Dec 23 06:48:41 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:48:41 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:48:41 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Clearing queue after UPNP request Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::ClearQueue Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::stop Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 06:49:29 musical-fidelity volumio[7436]: verbose: UNSET VOLATILE Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::stPlaybackTimer Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::updateTrackBlock Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrackBlock Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::serviceStop Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:49:29 musical-fidelity volumio[7436]: info: ControllerMpd::stop Dec 23 06:49:29 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::clearPlayQueue Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::saveQueue Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushQueue Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Dec 23 06:49:29 musical-fidelity volumio[7436]: ---------------------------- MPD announces state update: player Dec 23 06:49:29 musical-fidelity volumio[7436]: info: sendMpdCommand stop took 111 milliseconds Dec 23 06:49:29 musical-fidelity volumio[7436]: info: ControllerMpd::getState Dec 23 06:49:29 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand status Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Dec 23 06:49:29 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:49:29 musical-fidelity volumio[7436]: info: sendMpdCommand status took 2 milliseconds Dec 23 06:49:29 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: ControllerMpd::pushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::servicePushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:49:29 musical-fidelity volumio[7436]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 06:49:29 musical-fidelity volumio[7436]: verbose: CURRENT POSITION 0 Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState stateService stop Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState currentStatus stop Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:49:29 musical-fidelity volumio[7436]: info: No code Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:49:29 musical-fidelity volumio[7436]: info: ------------------------------ 31ms Dec 23 06:49:29 musical-fidelity volumio[7436]: error: updateQueue error: null Dec 23 06:49:29 musical-fidelity volumio[7436]: info: ------------------------------ 31ms Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Starting UPNP Playback Dec 23 06:49:29 musical-fidelity volumio[7436]: info: Preparing playback through UPNP Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioGetState Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CorePlayQueue::getTrack 0 Dec 23 06:49:29 musical-fidelity volumio[7436]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Dec 23 06:49:30 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ------------------------------ 2ms Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Dec 23 06:49:30 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ------------------------------ 6ms Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Dec 23 06:49:30 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ------------------------------ 4ms Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Dec 23 06:49:30 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Dec 23 06:49:30 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ------------------------------ 5ms Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ------------------------------ 3ms Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Dec 23 06:49:30 musical-fidelity volumio[7436]: ---------------------------- MPD announces system playlist update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Ignoring MPD Status Update Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ------------------------------ 2ms Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Dec 23 06:49:30 musical-fidelity volumio[7436]: ---------------------------- MPD announces state update: player Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ControllerMpd::getState Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand status Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Dec 23 06:49:30 musical-fidelity volumio[7436]: ---------------------------- MPD announces state update: player Dec 23 06:49:30 musical-fidelity volumio[7436]: info: sendMpdCommand status took 3 milliseconds Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ControllerMpd::getState Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand status Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseState Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 06:49:30 musical-fidelity volumio[7436]: info: sendMpdCommand status took 4 milliseconds Dec 23 06:49:30 musical-fidelity volumio[7436]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseState Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseTrackInfo Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ControllerMpd::pushState Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::servicePushState Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: In UPNP mode Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"恰似你的温柔","artist":"陈佳","album":"又见邓丽君","uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjYzA3OGZiZDFjNzNlNDkyYWYyZjFlMTgxOTZjYzE2MF82MS5tcDQ/0.flac?token=1766476168~MmZkODQxMWUyNWJhMDY2NWU0Nzk3ZTAyOTY5ZjE1YWZiOTMwMWI1ZQ==","trackType":"tidal"} Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: CURRENT POSITION 0 Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState stateService play Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState currentStatus stop Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ------------------------------ 31ms Dec 23 06:49:30 musical-fidelity volumio[7436]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: ControllerMpd::parseTrackInfo Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ControllerMpd::pushState Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::servicePushState Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: In UPNP mode Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"208 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"陈佳","album":"又见邓丽君","uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjYzA3OGZiZDFjNzNlNDkyYWYyZjFlMTgxOTZjYzE2MF82MS5tcDQ/0.flac?token=1766476168~MmZkODQxMWUyNWJhMDY2NWU0Nzk3ZTAyOTY5ZjE1YWZiOTMwMWI1ZQ==","trackType":"tidal"} Dec 23 06:49:30 musical-fidelity volumio[7436]: verbose: CURRENT POSITION 0 Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState stateService play Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreStateMachine::syncState currentStatus play Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Received an update from plugin. extracting info from payload Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreStateMachine::pushState Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::volumioPushState Dec 23 06:49:30 musical-fidelity volumio[7436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 06:49:30 musical-fidelity volumio[7436]: info: ------------------------------ 61ms Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:30 musical-fidelity volumio[7436]: info: Updating RAAT Signal Path Dec 23 06:49:58 musical-fidelity volumio[7436]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 06:49:58 musical-fidelity volumio[7436]: { Error: connect ETIMEDOUT 157.240.3.8:443 Dec 23 06:49:58 musical-fidelity volumio[7436]: at Object._errnoException (util.js:1022:11) Dec 23 06:49:58 musical-fidelity volumio[7436]: at _exceptionWithHostPort (util.js:1044:20) Dec 23 06:49:58 musical-fidelity volumio[7436]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Dec 23 06:49:58 musical-fidelity volumio[7436]: code: 'ETIMEDOUT', Dec 23 06:49:58 musical-fidelity volumio[7436]: errno: 'ETIMEDOUT', Dec 23 06:49:58 musical-fidelity volumio[7436]: syscall: 'connect', Dec 23 06:49:58 musical-fidelity volumio[7436]: address: '157.240.3.8', Dec 23 06:49:58 musical-fidelity volumio[7436]: port: 443 } Dec 23 06:49:58 musical-fidelity volumio[7436]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 06:49:59 musical-fidelity sudo[9274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 06:48 Dec 23 06:49:59 musical-fidelity sudo[9274]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"