-- Logs begin at Tue 2024-08-20 04:06:17 UTC, end at Tue 2024-08-20 08:55:06 UTC. -- Aug 20 08:54:01 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:09 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:12 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:12 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:13 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:13 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:15 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:16 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:17 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:17 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:18 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:19 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:21 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:25 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Aug 20 08:54:29 volumio shairport-sync[1328]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Aug 20 08:54:29 volumio volumio[1060]: info: Airplay started streaming, receiving metadatas Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::volumioStop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::stop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:54:29 volumio volumio[1060]: verbose: UNSET VOLATILE Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::updateTrackBlock Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrackBlock Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::stPlaybackTimer Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:29 volumio shairport-sync[1328]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 20 08:54:29 volumio shairport-sync[1328]: Dload Upload Total Spent Left Speed Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::serviceStop Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::serviceStop Aug 20 08:54:29 volumio volumio[1060]: info: ControllerMpd::stop Aug 20 08:54:29 volumio shairport-sync[1328]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 % Total % Received % Xferd Average Speed Time Time Time Current Aug 20 08:54:29 volumio shairport-sync[1328]: Dload Upload Total Spent Left Speed Aug 20 08:54:29 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:54:29 volumio volumio[1060]: info: Airplay started streaming, receiving metadatas Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::volumioStop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::stop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:54:29 volumio volumio[1060]: verbose: UNSET VOLATILE Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:54:29 volumio volumio[1060]: info: Aug 20 08:54:29 volumio volumio[1060]: ---------------------------- MPD announces state update: player Aug 20 08:54:29 volumio volumio[1060]: info: sendMpdCommand stop took 35 milliseconds Aug 20 08:54:29 volumio volumio[1060]: info: ControllerMpd::getState Aug 20 08:54:29 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Aug 20 08:54:29 volumio volumio[1060]: info: sendMpdCommand status took 20 milliseconds Aug 20 08:54:29 volumio volumio[1060]: verbose: ControllerMpd::parseState Aug 20 08:54:29 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 08:54:29 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 20 08:54:29 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Aug 20 08:54:29 volumio volumio[1060]: info: ControllerMpd::pushState Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"TUFFI RR-906 - 无敌天碟Tutti RR-906.wav","artist":null,"album":null,"uri":"INTERNAL/Classic/TUFFI RR-906/TUFFI RR-906 - 无敌天碟Tutti RR-906.wav","trackType":"wav"} Aug 20 08:54:29 volumio volumio[1060]: verbose: CURRENT POSITION 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:29 volumio volumio[1060]: info: No code Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:29 volumio volumio[1060]: info: ------------------------------ 98ms Aug 20 08:54:29 volumio volumio[1060]: info: Aug 20 08:54:29 volumio volumio[1060]: ---------------------------- Client requests Start Airplay PlaybackRoutine Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Aug 20 08:54:29 volumio volumio[1060]: info: Airplay playback start Aug 20 08:54:29 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::volumioStop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::stop Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:54:29 volumio volumio[1060]: verbose: UNSET VOLATILE Aug 20 08:54:29 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 08:54:29 volumio shairport-sync[1328]: [234B blob data] Aug 20 08:54:29 volumio volumio[1060]: info: Aug 20 08:54:29 volumio volumio[1060]: ---------------------------- Client requests AirplayActive Aug 20 08:54:29 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Aug 20 08:54:29 volumio shairport-sync[1328]: [222B blob data] Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:30 volumio volumio[1060]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:30 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Aug 20 08:54:30 volumio volumio[1060]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X1 Aug 20 08:54:30 volumio volumio[1060]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreStateMachine::pushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Aug 20 08:54:31 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 08:54:33 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:37 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:41 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:44 volumio avahi-daemon[653]: server.c: Packet too short or invalid while reading response record. (Maybe a UTF-8 problem?) Aug 20 08:54:45 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:49 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:53 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:54:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:55:01 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 08:55:04 volumio volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 08:55:04 volumio volumio[1060]: { Error: connect ETIMEDOUT 69.63.186.30:443 Aug 20 08:55:04 volumio volumio[1060]: at Object._errnoException (util.js:1022:11) Aug 20 08:55:04 volumio volumio[1060]: at _exceptionWithHostPort (util.js:1044:20) Aug 20 08:55:04 volumio volumio[1060]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 20 08:55:04 volumio volumio[1060]: code: 'ETIMEDOUT', Aug 20 08:55:04 volumio volumio[1060]: errno: 'ETIMEDOUT', Aug 20 08:55:04 volumio volumio[1060]: syscall: 'connect', Aug 20 08:55:04 volumio volumio[1060]: address: '69.63.186.30', Aug 20 08:55:04 volumio volumio[1060]: port: 443 } Aug 20 08:55:04 volumio volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 08:55:06 volumio sudo[5232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 08:54 Aug 20 08:55:06 volumio sudo[5232]: 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="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"