-- Logs begin at Sun 2025-04-13 08:33:08 UTC, end at Tue 2025-04-22 17:32:00 UTC. -- Apr 22 17:31:09 volumio sudo[14362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 22 17:31:09 volumio sudo[14362]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 17:31:09 volumio sudo[14362]: pam_unix(sudo:session): session closed for user root Apr 22 17:31:09 volumio sudo[14364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 22 17:31:09 volumio sudo[14364]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 17:31:09 volumio sudo[14364]: pam_unix(sudo:session): session closed for user root Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::volumioGetVisibleSources Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::volumioGetState Apr 22 17:31:11 volumio volumio[32199]: info: CoreStateMachine::getState Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 22 17:31:11 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 22 17:31:11 volumio volumio[32199]: info: Listing playlists Apr 22 17:31:14 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 17:31:14 volumio volumio[32199]: info: CURURI: albums:// Apr 22 17:31:14 volumio volumio[32199]: listAlbums - loading Albums from cache Apr 22 17:31:14 volumio volumio[32199]: info: CoreCommandRouter::volumioGetQueue Apr 22 17:31:14 volumio volumio[32199]: info: CoreStateMachine::getQueue Apr 22 17:31:14 volumio volumio[32199]: info: CorePlayQueue::getQueue Apr 22 17:31:15 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 22 17:31:17 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 17:31:17 volumio volumio[32199]: info: CURURI: artists:// Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Camila%20Cabello/f026a632-c158-4c19-8181-eaa686fa3f02.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dua%20Lipa/e176aacb-5a8c-4bad-9964-f68955b1e8cd.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Maroon%205/577e75a9-61d3-406c-8851-975f0a676baf.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Owl%20City/ceebfe43-7c15-4ad7-8fc8-1becf240b2f0.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB/3cbfbd24-ad81-4dd6-8e76-bd7e97079f75.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/47677885-b7b4-4018-8436-7780010985e5.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A2%81%E9%9D%99%E8%8C%B9/a1eebc8d-80c7-4a55-8691-2e63c3717911.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%CE%BC%E2%80%99s/e6123bea-4cc0-4578-82b1-fc003fdb7290.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Post%20Malone/2ec9cc10-172b-4107-8a2f-7543fb39d768.jpg' Apr 22 17:31:17 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Taylor%20Swift/a81224cd-0f52-446d-98c8-5b8b44376a09.jpg' Apr 22 17:31:18 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/RADWIMPS/144caeb5-074d-41fc-adda-eb070df89ef6.jpg' Apr 22 17:31:18 volumio volumio[32199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/YOASOBI/40b7adcd-1da6-4e32-9e81-774d536652d1.jpg' Apr 22 17:31:19 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 17:31:19 volumio volumio[32199]: info: CURURI: albums:// Apr 22 17:31:19 volumio volumio[32199]: listAlbums - loading Albums from cache Apr 22 17:31:23 volumio volumio[32199]: info: CoreCommandRouter::volumioAddQueueItems Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::addQueueItems Apr 22 17:31:23 volumio volumio[32199]: info: CorePlayQueue::addQueueItems Apr 22 17:31:23 volumio volumio[32199]: info: Adding Item to queue: albums://%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB/%E4%B8%96%E7%95%8C%2F%E9%9D%92%E5%B9%B4 Apr 22 17:31:23 volumio volumio[32199]: info: Exploding uri albums://%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB/%E4%B8%96%E7%95%8C%2F%E9%9D%92%E5%B9%B4 in service mpd Apr 22 17:31:23 volumio volumio[32199]: info: CorePlayQueue::saveQueue Apr 22 17:31:23 volumio volumio[32199]: info: CoreCommandRouter::volumioPushQueue Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::updateTrackBlock Apr 22 17:31:23 volumio volumio[32199]: info: CorePlayQueue::getTrackBlock Apr 22 17:31:23 volumio volumio[32199]: info: CoreCommandRouter::volumioPlay Apr 22 17:31:23 volumio volumio[32199]: UNSET VOLATILE Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::getState Apr 22 17:31:23 volumio volumio[32199]: info: Stopping Airplay Playback and sending pause command to client via USR2 Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::play index 14 Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::stop Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 17:31:23 volumio volumio[32199]: UNSET VOLATILE Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::stPlaybackTimer Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::updateTrackBlock Apr 22 17:31:23 volumio volumio[32199]: info: CorePlayQueue::getTrackBlock Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::pushState Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::getState Apr 22 17:31:23 volumio volumio[32199]: info: CorePlayQueue::getTrack 0 Apr 22 17:31:23 volumio volumio[32199]: info: CoreCommandRouter::volumioPushState Apr 22 17:31:23 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 17:31:23 volumio volumio[32199]: info: CoreStateMachine::serviceStop Apr 22 17:31:23 volumio volumio[32199]: info: CorePlayQueue::getTrack 0 Apr 22 17:31:23 volumio volumio[32199]: info: CoreCommandRouter::serviceStop Apr 22 17:31:23 volumio volumio[32199]: info: ControllerMpd::stop Apr 22 17:31:24 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand stop Apr 22 17:31:24 volumio volumio[32199]: info: sending command... Apr 22 17:31:24 volumio volumio[32199]: info: parsing response... Apr 22 17:31:24 volumio volumio[32199]: info: CoreStateMachine::play index undefined Apr 22 17:31:24 volumio volumio[32199]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 17:31:24 volumio volumio[32199]: info: CorePlayQueue::getTrack 14 Apr 22 17:31:24 volumio volumio[32199]: info: CoreStateMachine::startPlaybackTimer Apr 22 17:31:24 volumio volumio[32199]: info: CorePlayQueue::getTrack 14 Apr 22 17:31:24 volumio volumio[32199]: info: ControllerMpd::clearAddPlayTracks NAS/SharedMusics/房东的猫/世界-青年/01 不来也不去.flac Apr 22 17:31:24 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand stop Apr 22 17:31:24 volumio volumio[32199]: info: sending command... Apr 22 17:31:24 volumio volumio[32199]: info: parsing response... Apr 22 17:31:24 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand clear Apr 22 17:31:24 volumio volumio[32199]: info: sending command... Apr 22 17:31:24 volumio volumio[32199]: info: Apr 22 17:31:24 volumio volumio[32199]: ---------------------------- MPD announces system playlist update Apr 22 17:31:24 volumio volumio[32199]: info: Ignoring MPD Status Update Apr 22 17:31:24 volumio volumio[32199]: info: parsing response... Apr 22 17:31:24 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand add "NAS/SharedMusics/房东的猫/世界-青年/01 不来也不去.flac" Apr 22 17:31:24 volumio volumio[32199]: info: sending command... Apr 22 17:31:24 volumio volumio[32199]: info: null Apr 22 17:31:24 volumio volumio[32199]: info: Apr 22 17:31:24 volumio volumio[32199]: ---------------------------- MPD announces system playlist update Apr 22 17:31:24 volumio volumio[32199]: info: Ignoring MPD Status Update Apr 22 17:31:24 volumio volumio[32199]: info: ------------------------------ 106ms Apr 22 17:31:24 volumio volumio[32199]: info: parsing response... Apr 22 17:31:24 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand play Apr 22 17:31:24 volumio volumio[32199]: info: sending command... Apr 22 17:31:24 volumio volumio[32199]: info: ------------------------------ 112ms Apr 22 17:31:24 volumio volumio[32199]: info: parsing response... Apr 22 17:31:24 volumio sudo[14409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 32350 Apr 22 17:31:24 volumio sudo[14409]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 17:31:24 volumio kernel: bcm2835-i2s 20203000.i2s: I2S SYNC error! Apr 22 17:31:24 volumio shairport-sync[32350]: {"time":1745243109124,"response":"airplayInactive Success"} Apr 22 17:31:24 volumio systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Apr 22 17:31:24 volumio systemd[1]: Unit shairport-sync.service entered failed state. Apr 22 17:31:24 volumio sudo[14409]: pam_unix(sudo:session): session closed for user root Apr 22 17:31:25 volumio volumio[32199]: info: Apr 22 17:31:25 volumio volumio[32199]: ---------------------------- MPD announces state update: player Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::getState Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand status Apr 22 17:31:25 volumio volumio[32199]: info: sending command... Apr 22 17:31:25 volumio volumio[32199]: info: Apr 22 17:31:25 volumio volumio[32199]: ---------------------------- MPD announces state update: player Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::getState Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand status Apr 22 17:31:25 volumio volumio[32199]: info: parsing response... Apr 22 17:31:25 volumio volumio[32199]: info: sending command... Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::parseState Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 22 17:31:25 volumio volumio[32199]: info: sending command... Apr 22 17:31:25 volumio volumio[32199]: info: Shairport-Sync paused with USR2 Apr 22 17:31:25 volumio volumio[32199]: info: parsing response... Apr 22 17:31:25 volumio volumio[32199]: info: parsing response... Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::parseState Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::parseTrackInfo Apr 22 17:31:25 volumio volumio[32199]: info: sending command... Apr 22 17:31:25 volumio volumio[32199]: info: ControllerMpd::pushState Apr 22 17:31:25 volumio volumio[32199]: info: CoreCommandRouter::servicePushState Apr 22 17:31:25 volumio volumio[32199]: info: CoreStateMachine::syncState Apr 22 17:31:25 volumio volumio[32199]: info: CorePlayQueue::getTrack 14 Apr 22 17:31:25 volumio volumio[32199]: info: STATE SERVICE {"status":"play","position":0,"seek":882,"duration":308,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"725 Kbps","isStreaming":false,"title":"不来也不去","artist":"房东的猫","album":"世界/青年","uri":"NAS/SharedMusics/房东的猫/世界-青年/01 不来也不去.flac","trackType":"flac"} Apr 22 17:31:26 volumio volumio[32199]: info: CURRENT POSITION 14 Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::syncState stateService play Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::syncState currentStatus stop Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::pushState Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::getState Apr 22 17:31:26 volumio volumio[32199]: info: CorePlayQueue::getTrack 14 Apr 22 17:31:26 volumio volumio[32199]: info: CoreCommandRouter::volumioPushState Apr 22 17:31:26 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 17:31:26 volumio volumio[32199]: info: ------------------------------ 850ms Apr 22 17:31:26 volumio volumio[32199]: info: parsing response... Apr 22 17:31:26 volumio volumio[32199]: info: ControllerMpd::parseTrackInfo Apr 22 17:31:26 volumio volumio[32199]: info: ControllerMpd::pushState Apr 22 17:31:26 volumio volumio[32199]: info: CoreCommandRouter::servicePushState Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::syncState Apr 22 17:31:26 volumio volumio[32199]: info: CorePlayQueue::getTrack 14 Apr 22 17:31:26 volumio volumio[32199]: info: STATE SERVICE {"status":"play","position":0,"seek":1137,"duration":308,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"678 Kbps","isStreaming":false,"title":"不来也不去","artist":"房东的猫","album":"世界/青年","uri":"NAS/SharedMusics/房东的猫/世界-青年/01 不来也不去.flac","trackType":"flac"} Apr 22 17:31:26 volumio volumio[32199]: info: CURRENT POSITION 14 Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::syncState stateService play Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::syncState currentStatus play Apr 22 17:31:26 volumio volumio[32199]: info: Received an update from plugin. extracting info from payload Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::pushState Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::getState Apr 22 17:31:26 volumio volumio[32199]: info: CorePlayQueue::getTrack 14 Apr 22 17:31:26 volumio volumio[32199]: info: CoreCommandRouter::volumioPushState Apr 22 17:31:26 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::pushState Apr 22 17:31:26 volumio volumio[32199]: info: CoreStateMachine::getState Apr 22 17:31:26 volumio volumio[32199]: info: CorePlayQueue::getTrack 14 Apr 22 17:31:26 volumio volumio[32199]: info: CoreCommandRouter::volumioPushState Apr 22 17:31:26 volumio volumio[32199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 17:31:26 volumio volumio[32199]: info: ------------------------------ 1281ms Apr 22 17:31:26 volumio volumio[32199]: info: Restarting Shairport-Sync after stop Apr 22 17:31:27 volumio sudo[14424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 17:31:27 volumio sudo[14424]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 17:31:27 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 22 17:31:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 17:31:27 volumio sudo[14424]: pam_unix(sudo:session): session closed for user root Apr 22 17:31:27 volumio volumio[32199]: info: Shairport-Sync retarted Apr 22 17:31:30 volumio volumio[32199]: info: CoreCommandRouter::volumioAddQueueItems Apr 22 17:31:30 volumio volumio[32199]: info: CoreStateMachine::addQueueItems Apr 22 17:31:30 volumio volumio[32199]: info: CorePlayQueue::addQueueItems Apr 22 17:31:30 volumio volumio[32199]: info: Adding Item to queue: albums://%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB/%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB Apr 22 17:31:30 volumio volumio[32199]: info: Exploding uri albums://%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB/%E6%88%BF%E4%B8%9C%E7%9A%84%E7%8C%AB in service mpd Apr 22 17:31:30 volumio volumio[32199]: info: CorePlayQueue::saveQueue Apr 22 17:31:30 volumio volumio[32199]: info: CoreCommandRouter::volumioPushQueue Apr 22 17:31:30 volumio volumio[32199]: info: CoreStateMachine::updateTrackBlock Apr 22 17:31:30 volumio volumio[32199]: info: CorePlayQueue::getTrackBlock Apr 22 17:31:52 volumio volumio[32199]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 17:31:52 volumio volumio[32199]: { Error: connect ETIMEDOUT 108.160.167.174:80 Apr 22 17:31:52 volumio volumio[32199]: at Object._errnoException (util.js:1022:11) Apr 22 17:31:52 volumio volumio[32199]: at _exceptionWithHostPort (util.js:1044:20) Apr 22 17:31:52 volumio volumio[32199]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 22 17:31:52 volumio volumio[32199]: code: 'ETIMEDOUT', Apr 22 17:31:52 volumio volumio[32199]: errno: 'ETIMEDOUT', Apr 22 17:31:52 volumio volumio[32199]: syscall: 'connect', Apr 22 17:31:52 volumio volumio[32199]: address: '108.160.167.174', Apr 22 17:31:52 volumio volumio[32199]: port: 80 } Apr 22 17:31:52 volumio volumio[32199]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 17:32:00 volumio sudo[14512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 17:31 Apr 22 17:32:00 volumio sudo[14512]: 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"