-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sun 2025-07-06 00:05:18 CST. -- Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/15 - Femme fatale (starring Tim Keegan).mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=Emilie%20Simon/Emilie%20Simon/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F15%20-%20Femme%20fatale%20(starring%20Tim%20Keegan).mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/15 - Femme fatale (starring Tim Keegan).mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/04 - He's a Pirate.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=David%20Garrett/Encore/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F04%20-%20He's%20a%20Pirate.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/04 - He's a Pirate.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/01 - A Berrer Day.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=JTL/Enter%20The%20Dragon/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F01%20-%20A%20Berrer%20Day.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/01 - A Berrer Day.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/15 - It Hurts (瑞典).mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=Lena%20Philipsson/Eurovision%20Song%20Contest%20Istanbul%202004/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F15%20-%20It%20Hurts%20(%E7%91%9E%E5%85%B8).mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/15 - It Hurts (瑞典).mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/05 - So Close.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=EVANESCENCE/Evanescence%20EP/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F05%20-%20So%20Close.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/05 - So Close.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/02 - Everybody.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=Ingrid%20Michaelson/Everybody/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F02%20-%20Everybody.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/02 - Everybody.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/01 - Lonely Girl.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=Dala/Everyone%20Is%20Someone/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F01%20-%20Lonely%20Girl.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/01 - Lonely Girl.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/10 - Stoppin' the Love.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=KT%20Tunstall/Eye%20to%20the%20Telescope/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F10%20-%20Stoppin'%20the%20Love.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/10 - Stoppin' the Love.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/01 - Eyes On Me.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=%E7%8E%8B%E8%8F%B2/Eyes%20On%20Me/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F01%20-%20Eyes%20On%20Me.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/01 - Eyes On Me.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/02 - 红豆.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=%E7%8E%8B%E8%8F%B2/Eyes%20On%20Me/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F02%20-%20%E7%BA%A2%E8%B1%86.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/02 - 红豆.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/04 - Sometimes When We Touch.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=Olivia%20Ong/Fall%20in%20Love%20with/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F04%20-%20Sometimes%20When%20We%20Touch.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/04 - Sometimes When We Touch.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/03 - The Reason Why My Heart's in Misery.mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=Lay%20Low/Farewell%20Good%20Night's%20Sleep/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F03%20-%20The%20Reason%20Why%20My%20Heart's%20in%20Misery.mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/03 - The Reason Why My Heart's in Misery.mp3 Jul 06 00:04:00 volumio volumio[7500]: info: Exploding uri music-library/USB/MUSIC/03 - The Reason Why My Heart's in Misery(1).mp3 in service mpd Jul 06 00:04:00 volumio volumio[7500]: info: ALBUMART /albumart?cacheid=725&web=Lay%20Low/Farewell%20Good%20Night's%20Sleep/extralarge&path=%2Fmnt%2FUSB%2FMUSIC%2F03%20-%20The%20Reason%20Why%20My%20Heart's%20in%20Misery(1).mp3&metadata=false Jul 06 00:04:00 volumio volumio[7500]: info: URI /mnt/USB/MUSIC/03 - The Reason Why My Heart's in Misery(1).mp3 Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::volumioPause Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::pause Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::stPlaybackTimer Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::servicePause Jul 06 00:04:16 volumio volumio[7500]: info: CorePlayQueue::getTrack 0 Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::servicePause Jul 06 00:04:16 volumio volumio[7500]: info: ControllerMpd::pause Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::sendMpdCommand pause Jul 06 00:04:16 volumio volumio[7500]: info: Jul 06 00:04:16 volumio volumio[7500]: ---------------------------- MPD announces state update: player Jul 06 00:04:16 volumio volumio[7500]: info: sendMpdCommand pause took 7 milliseconds Jul 06 00:04:16 volumio volumio[7500]: info: ControllerMpd::getState Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::sendMpdCommand status Jul 06 00:04:16 volumio volumio[7500]: info: Jul 06 00:04:16 volumio volumio[7500]: ---------------------------- MPD announces state update: player Jul 06 00:04:16 volumio volumio[7500]: info: ControllerMpd::getState Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::sendMpdCommand status Jul 06 00:04:16 volumio volumio[7500]: info: Jul 06 00:04:16 volumio volumio[7500]: ---------------------------- MPD announces state update: player Jul 06 00:04:16 volumio volumio[7500]: info: ControllerMpd::getState Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::sendMpdCommand status Jul 06 00:04:16 volumio volumio[7500]: info: sendMpdCommand status took 11 milliseconds Jul 06 00:04:16 volumio volumio[7500]: info: sendMpdCommand status took 7 milliseconds Jul 06 00:04:16 volumio volumio[7500]: info: sendMpdCommand status took 5 milliseconds Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::parseState Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::parseState Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::parseState Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 00:04:16 volumio volumio[7500]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 06 00:04:16 volumio volumio[7500]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 06 00:04:16 volumio volumio[7500]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::parseTrackInfo Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::parseTrackInfo Jul 06 00:04:16 volumio volumio[7500]: verbose: ControllerMpd::parseTrackInfo Jul 06 00:04:16 volumio volumio[7500]: info: ControllerMpd::pushState Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::servicePushState Jul 06 00:04:16 volumio volumio[7500]: info: CorePlayQueue::getTrack 0 Jul 06 00:04:16 volumio volumio[7500]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":241468,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"半生缘","artist":"达明一派","album":"你还爱我吗","uri":"USB/MUSIC/02 - 半生缘.mp3","trackType":"mp3"} Jul 06 00:04:16 volumio volumio[7500]: verbose: CURRENT POSITION 0 Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::syncState stateService pause Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::syncState currentStatus pause Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::pushState Jul 06 00:04:16 volumio volumio[7500]: info: CorePlayQueue::getTrack 0 Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::volumioPushState Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::stPlaybackTimer Jul 06 00:04:16 volumio volumio[7500]: info: ControllerMpd::pushState Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::servicePushState Jul 06 00:04:16 volumio volumio[7500]: info: CorePlayQueue::getTrack 0 Jul 06 00:04:16 volumio volumio[7500]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":241468,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"半生缘","artist":"达明一派","album":"你还爱我吗","uri":"USB/MUSIC/02 - 半生缘.mp3","trackType":"mp3"} Jul 06 00:04:16 volumio volumio[7500]: verbose: CURRENT POSITION 0 Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::syncState stateService pause Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::syncState currentStatus pause Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::pushState Jul 06 00:04:16 volumio volumio[7500]: info: CorePlayQueue::getTrack 0 Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::volumioPushState Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::stPlaybackTimer Jul 06 00:04:16 volumio volumio[7500]: info: ControllerMpd::pushState Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::servicePushState Jul 06 00:04:16 volumio volumio[7500]: info: CorePlayQueue::getTrack 0 Jul 06 00:04:16 volumio volumio[7500]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":241468,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"半生缘","artist":"达明一派","album":"你还爱我吗","uri":"USB/MUSIC/02 - 半生缘.mp3","trackType":"mp3"} Jul 06 00:04:16 volumio volumio[7500]: verbose: CURRENT POSITION 0 Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::syncState stateService pause Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::syncState currentStatus pause Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::pushState Jul 06 00:04:16 volumio volumio[7500]: info: CorePlayQueue::getTrack 0 Jul 06 00:04:16 volumio volumio[7500]: info: CoreCommandRouter::volumioPushState Jul 06 00:04:16 volumio volumio[7500]: info: CoreStateMachine::stPlaybackTimer Jul 06 00:04:16 volumio volumio[7500]: info: ------------------------------ 103ms Jul 06 00:04:16 volumio volumio[7500]: info: ------------------------------ 95ms Jul 06 00:04:16 volumio volumio[7500]: info: ------------------------------ 94ms Jul 06 00:04:30 volumio volumio-remote-updater[585]: zsync done Jul 06 00:04:31 volumio volumio-remote-updater[585]: PROGRESS: 90, STATUS: "Cleaning old files", ETA: "1m" Jul 06 00:04:32 volumio volumio-remote-updater[585]: /bin/cp: cannot stat '/data/volumio_factory.sqsh': No such file or directory Jul 06 00:04:33 volumio volumio-remote-updater[585]: /bin/rm: cannot remove '/data/volumio_factory.sqsh': No such file or directory Jul 06 00:04:33 volumio volumio-remote-updater[585]: PROGRESS: 95, STATUS: "Finalizing update", ETA: "30s" Jul 06 00:04:34 volumio volumio-remote-updater[585]: PROGRESS: 100, STATUS: "success", MESSAGE: "Successfully updated to \"3.819\" version. System restart required." Jul 06 00:04:34 volumio volumio-remote-updater[585]: No test mode Jul 06 00:04:34 volumio volumio-remote-updater[585]: No alpha test mode Jul 06 00:04:35 volumio volumio-remote-updater[585]: [2025-07-05 16:04:35] [disconnect] Disconnect close local:[1000] remote:[1000] Jul 06 00:04:35 volumio volumio-remote-updater[585]: [2025-07-05 16:04:35] [info] asio async_write error: system:32 (Broken pipe) Jul 06 00:04:35 volumio volumio-remote-updater[585]: [2025-07-05 16:04:35] [fatal] handle_write_frame error: websocketpp.transport:2 (Underlying Transport Error) Jul 06 00:04:35 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Jul 06 00:04:35 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jul 06 00:04:35 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Jul 06 00:04:35 volumio systemd[1]: Stopped volumio-remote-updater.service. Jul 06 00:04:35 volumio systemd[1]: Started volumio-remote-updater.service. Jul 06 00:04:35 volumio volumio-remote-updater[8209]: Error: No active session Jul 06 00:04:35 volumio volumio-remote-updater[8209]: [2025-07-06 00:04:35] [connect] Successful connection Jul 06 00:04:35 volumio volumio-remote-updater[8209]: [2025-07-06 00:04:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751731475 101 Jul 06 00:04:35 volumio volumio[7500]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jul 06 00:05:16 volumio volumio[7500]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 00:05:16 volumio volumio[7500]: Error: connect ETIMEDOUT 173.255.213.90:80 Jul 06 00:05:16 volumio volumio[7500]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 06 00:05:16 volumio volumio[7500]: errno: -110, Jul 06 00:05:16 volumio volumio[7500]: code: 'ETIMEDOUT', Jul 06 00:05:16 volumio volumio[7500]: syscall: 'connect', Jul 06 00:05:16 volumio volumio[7500]: address: '173.255.213.90', Jul 06 00:05:16 volumio volumio[7500]: port: 80 Jul 06 00:05:16 volumio volumio[7500]: } Jul 06 00:05:16 volumio volumio[7500]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 00:05:18 volumio sudo[8285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-06 00:04 Jul 06 00:05:18 volumio sudo[8285]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"