-- Logs begin at Sun 2025-04-13 08:33:08 UTC, end at Wed 2025-04-16 17:51:09 UTC. -- Apr 16 17:50:13 volumio volumio[25277]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 16 17:50:13 volumio volumio[25277]: info: CURURI: playlists Apr 16 17:50:13 volumio volumio[25277]: info: Listing playlists Apr 16 17:50:21 volumio volumio[25277]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 16 17:50:21 volumio volumio[25277]: info: CURURI: albums:// Apr 16 17:50:21 volumio volumio[25277]: listAlbums - loading Albums from cache Apr 16 17:50:24 volumio volumio[25277]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 16 17:50:24 volumio volumio[25277]: info: CURURI: artists:// Apr 16 17:50:30 volumio volumio[25277]: info: CoreCommandRouter::volumioAddQueueItems Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::addQueueItems Apr 16 17:50:30 volumio volumio[25277]: info: CorePlayQueue::addQueueItems Apr 16 17:50:30 volumio volumio[25277]: info: Adding Item to queue: artists://%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB Apr 16 17:50:30 volumio volumio[25277]: info: Exploding uri artists://%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB in service mpd Apr 16 17:50:30 volumio volumio[25277]: info: CorePlayQueue::saveQueue Apr 16 17:50:30 volumio volumio[25277]: info: CoreCommandRouter::volumioPushQueue Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::updateTrackBlock Apr 16 17:50:30 volumio volumio[25277]: info: CorePlayQueue::getTrackBlock Apr 16 17:50:30 volumio volumio[25277]: info: CoreCommandRouter::volumioPlay Apr 16 17:50:30 volumio volumio[25277]: UNSET VOLATILE Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::getState Apr 16 17:50:30 volumio volumio[25277]: info: Stopping Airplay Playback and sending pause command to client via USR2 Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::play index 12 Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::stop Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 17:50:30 volumio volumio[25277]: UNSET VOLATILE Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::updateTrackBlock Apr 16 17:50:30 volumio volumio[25277]: info: CorePlayQueue::getTrackBlock Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::stPlaybackTimer Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::pushState Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::getState Apr 16 17:50:30 volumio volumio[25277]: info: CorePlayQueue::getTrack 0 Apr 16 17:50:30 volumio volumio[25277]: info: CoreCommandRouter::volumioPushState Apr 16 17:50:30 volumio volumio[25277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::serviceStop Apr 16 17:50:30 volumio volumio[25277]: info: CorePlayQueue::getTrack 0 Apr 16 17:50:30 volumio volumio[25277]: info: CoreCommandRouter::serviceStop Apr 16 17:50:30 volumio volumio[25277]: info: ControllerMpd::stop Apr 16 17:50:30 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand stop Apr 16 17:50:30 volumio volumio[25277]: info: sending command... Apr 16 17:50:30 volumio volumio[25277]: info: parsing response... Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::play index undefined Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 17:50:30 volumio volumio[25277]: info: CorePlayQueue::getTrack 12 Apr 16 17:50:30 volumio volumio[25277]: info: CoreStateMachine::startPlaybackTimer Apr 16 17:50:31 volumio volumio[25277]: info: CorePlayQueue::getTrack 12 Apr 16 17:50:31 volumio volumio[25277]: info: ControllerMpd::clearAddPlayTracks NAS/SharedMusics/房东的猫/世界-青年/01 不来也不去.flac Apr 16 17:50:31 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand stop Apr 16 17:50:31 volumio volumio[25277]: info: sending command... Apr 16 17:50:31 volumio volumio[25277]: info: parsing response... Apr 16 17:50:31 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand clear Apr 16 17:50:31 volumio volumio[25277]: info: sending command... Apr 16 17:50:31 volumio sudo[28133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 10185 Apr 16 17:50:31 volumio volumio[25277]: info: Apr 16 17:50:31 volumio volumio[25277]: ---------------------------- MPD announces system playlist update Apr 16 17:50:31 volumio volumio[25277]: info: Ignoring MPD Status Update Apr 16 17:50:31 volumio volumio[25277]: info: parsing response... Apr 16 17:50:31 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand add "NAS/SharedMusics/房东的猫/世界-青年/01 不来也不去.flac" Apr 16 17:50:31 volumio volumio[25277]: info: sending command... Apr 16 17:50:31 volumio sudo[28133]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 17:50:31 volumio volumio[25277]: info: null Apr 16 17:50:31 volumio volumio[25277]: info: Apr 16 17:50:31 volumio volumio[25277]: ---------------------------- MPD announces system playlist update Apr 16 17:50:31 volumio volumio[25277]: info: Ignoring MPD Status Update Apr 16 17:50:31 volumio volumio[25277]: info: ------------------------------ 164ms Apr 16 17:50:31 volumio volumio[25277]: info: parsing response... Apr 16 17:50:31 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand play Apr 16 17:50:31 volumio volumio[25277]: info: sending command... Apr 16 17:50:31 volumio shairport-sync[10185]: {"time":1744823455575,"response":"airplayInactive Success"} Apr 16 17:50:31 volumio systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Apr 16 17:50:31 volumio sudo[28133]: pam_unix(sudo:session): session closed for user root Apr 16 17:50:31 volumio systemd[1]: Unit shairport-sync.service entered failed state. Apr 16 17:50:31 volumio volumio[25277]: info: ------------------------------ 289ms Apr 16 17:50:31 volumio volumio[25277]: info: parsing response... Apr 16 17:50:31 volumio kernel: bcm2835-i2s 20203000.i2s: I2S SYNC error! Apr 16 17:50:32 volumio volumio[25277]: info: Apr 16 17:50:32 volumio volumio[25277]: ---------------------------- MPD announces state update: player Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::getState Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand status Apr 16 17:50:32 volumio volumio[25277]: info: sending command... Apr 16 17:50:32 volumio volumio[25277]: info: Shairport-Sync paused with USR2 Apr 16 17:50:32 volumio volumio[25277]: info: Apr 16 17:50:32 volumio volumio[25277]: ---------------------------- MPD announces state update: player Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::getState Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand status Apr 16 17:50:32 volumio volumio[25277]: info: parsing response... Apr 16 17:50:32 volumio volumio[25277]: info: sending command... Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::parseState Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 17:50:32 volumio volumio[25277]: info: sending command... Apr 16 17:50:32 volumio volumio[25277]: info: parsing response... Apr 16 17:50:32 volumio volumio[25277]: info: parsing response... Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::parseState Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::parseTrackInfo Apr 16 17:50:32 volumio volumio[25277]: info: sending command... Apr 16 17:50:32 volumio volumio[25277]: info: ControllerMpd::pushState Apr 16 17:50:32 volumio volumio[25277]: info: CoreCommandRouter::servicePushState Apr 16 17:50:32 volumio volumio[25277]: info: CoreStateMachine::syncState Apr 16 17:50:32 volumio volumio[25277]: info: CorePlayQueue::getTrack 12 Apr 16 17:50:32 volumio volumio[25277]: info: STATE SERVICE {"status":"play","position":0,"seek":557,"duration":308,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"584 Kbps","isStreaming":false,"title":"不来也不去","artist":"房东的猫","album":"世界/青年","uri":"NAS/SharedMusics/房东的猫/世界-青年/01 不来也不去.flac","trackType":"flac"} Apr 16 17:50:32 volumio volumio[25277]: info: CURRENT POSITION 12 Apr 16 17:50:32 volumio volumio[25277]: info: CoreStateMachine::syncState stateService play Apr 16 17:50:32 volumio volumio[25277]: info: CoreStateMachine::syncState currentStatus stop Apr 16 17:50:32 volumio volumio[25277]: info: CoreStateMachine::pushState Apr 16 17:50:32 volumio volumio[25277]: info: CoreStateMachine::getState Apr 16 17:50:32 volumio volumio[25277]: info: CorePlayQueue::getTrack 12 Apr 16 17:50:32 volumio volumio[25277]: info: CoreCommandRouter::volumioPushState Apr 16 17:50:33 volumio volumio[25277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 17:50:33 volumio volumio[25277]: info: ------------------------------ 914ms Apr 16 17:50:33 volumio volumio[25277]: info: parsing response... Apr 16 17:50:33 volumio volumio[25277]: info: ControllerMpd::parseTrackInfo Apr 16 17:50:33 volumio volumio[25277]: info: ControllerMpd::pushState Apr 16 17:50:33 volumio volumio[25277]: info: CoreCommandRouter::servicePushState Apr 16 17:50:33 volumio volumio[25277]: info: CoreStateMachine::syncState Apr 16 17:50:33 volumio volumio[25277]: info: CorePlayQueue::getTrack 12 Apr 16 17:50:33 volumio volumio[25277]: info: STATE SERVICE {"status":"play","position":0,"seek":719,"duration":308,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"573 Kbps","isStreaming":false,"title":"不来也不去","artist":"房东的猫","album":"世界/青年","uri":"NAS/SharedMusics/房东的猫/世界-青年/01 不来也不去.flac","trackType":"flac"} Apr 16 17:50:33 volumio volumio[25277]: info: CURRENT POSITION 12 Apr 16 17:50:33 volumio volumio[25277]: info: CoreStateMachine::syncState stateService play Apr 16 17:50:33 volumio volumio[25277]: info: CoreStateMachine::syncState currentStatus play Apr 16 17:50:33 volumio volumio[25277]: info: Received an update from plugin. extracting info from payload Apr 16 17:50:33 volumio volumio[25277]: info: CoreStateMachine::pushState Apr 16 17:50:33 volumio volumio[25277]: info: CoreStateMachine::getState Apr 16 17:50:33 volumio volumio[25277]: info: CorePlayQueue::getTrack 12 Apr 16 17:50:33 volumio volumio[25277]: info: CoreCommandRouter::volumioPushState Apr 16 17:50:33 volumio volumio[25277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 17:50:33 volumio volumio[25277]: info: CoreStateMachine::pushState Apr 16 17:50:33 volumio volumio[25277]: info: CoreStateMachine::getState Apr 16 17:50:33 volumio volumio[25277]: info: CorePlayQueue::getTrack 12 Apr 16 17:50:33 volumio volumio[25277]: info: CoreCommandRouter::volumioPushState Apr 16 17:50:33 volumio volumio[25277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 17:50:33 volumio volumio[25277]: info: ------------------------------ 1296ms Apr 16 17:50:33 volumio volumio[25277]: info: Restarting Shairport-Sync after stop Apr 16 17:50:34 volumio sudo[28148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 17:50:34 volumio sudo[28148]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 17:50:34 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 16 17:50:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 16 17:50:34 volumio sudo[28148]: pam_unix(sudo:session): session closed for user root Apr 16 17:50:34 volumio volumio[25277]: info: Shairport-Sync retarted Apr 16 17:50:49 volumio volumio[25277]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 16 17:50:49 volumio volumio[25277]: info: CURURI: albums:// Apr 16 17:50:49 volumio volumio[25277]: listAlbums - loading Albums from cache Apr 16 17:51:01 volumio volumio[25277]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 17:51:01 volumio volumio[25277]: { Error: connect ETIMEDOUT 199.59.148.96:80 Apr 16 17:51:01 volumio volumio[25277]: at Object._errnoException (util.js:1022:11) Apr 16 17:51:01 volumio volumio[25277]: at _exceptionWithHostPort (util.js:1044:20) Apr 16 17:51:01 volumio volumio[25277]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 16 17:51:01 volumio volumio[25277]: code: 'ETIMEDOUT', Apr 16 17:51:01 volumio volumio[25277]: errno: 'ETIMEDOUT', Apr 16 17:51:01 volumio volumio[25277]: syscall: 'connect', Apr 16 17:51:01 volumio volumio[25277]: address: '199.59.148.96', Apr 16 17:51:01 volumio volumio[25277]: port: 80 } Apr 16 17:51:01 volumio volumio[25277]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 17:51:09 volumio sudo[28226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-16 17:50 Apr 16 17:51:09 volumio sudo[28226]: 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="ac2a0afddd424ea15d982b8c75eeec3a8648a313" VOLUMIO_FE_VERSION="700492006632ef61a5a17e6a3be4f37c790f883c" VOLUMIO_BE_VERSION="b2e85b2e8facd2d741f2fc40be426a34804b56fc" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue May 5 18:38:33 CEST 2020" VOLUMIO_VERSION="2.773" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="9e13d1d50d1f834c8c0294a226468074"