-- Logs begin at Tue 2025-10-21 14:13:02 UTC, end at Fri 2025-10-24 13:32:19 UTC. -- Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::volumioNext Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::next Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::stop Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 13:31:03 volumio volumio[1018]: verbose: UNSET VOLATILE Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::stPlaybackTimer Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 30 Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::serviceStop Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 30 Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::serviceStop Oct 24 13:31:03 volumio volumio[1018]: info: ControllerMpd::stop Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 13:31:03 volumio volumio[1018]: info: Oct 24 13:31:03 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand stop took 35 milliseconds Oct 24 13:31:03 volumio volumio[1018]: info: ControllerMpd::getState Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::play index undefined Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::clearAddPlayTracks NAS/MS-148/Super Hit - June 2025/DJ Snake/DJ Snake - Paradise.mp3 Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand stop took 1 milliseconds Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 13:31:03 volumio volumio[1018]: info: Oct 24 13:31:03 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 24 13:31:03 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand clear took 1 milliseconds Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "NAS/MS-148/Super Hit - June 2025/DJ Snake/DJ Snake - Paradise.mp3" Oct 24 13:31:03 volumio volumio[1018]: info: ControllerMpd::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: 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":"Nightbird","artist":"DJ Snake","album":"Nightbird","uri":"NAS/MS-148/Super Hit - June 2025/DJ Snake/DJ Snake - Nightbird.mp3","trackType":"mp3"} Oct 24 13:31:03 volumio volumio[1018]: verbose: CURRENT POSITION 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::syncState stateService stop Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 24 13:31:03 volumio volumio[1018]: info: No code Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 24 13:31:03 volumio volumio[1018]: info: ------------------------------ 8ms Oct 24 13:31:03 volumio volumio[1018]: error: updateQueue error: null Oct 24 13:31:03 volumio volumio[1018]: info: Oct 24 13:31:03 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 24 13:31:03 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 24 13:31:03 volumio volumio[1018]: info: ------------------------------ 7ms Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand add "NAS/MS-148/Super Hit - June 2025/DJ Snake/DJ Snake - Paradise.mp3" took 7 milliseconds Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Oct 24 13:31:03 volumio volumio[1018]: info: ------------------------------ 3ms Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand play took 2 milliseconds Oct 24 13:31:03 volumio volumio[1018]: info: Oct 24 13:31:03 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 24 13:31:03 volumio volumio[1018]: info: ControllerMpd::getState Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:31:03 volumio volumio[1018]: info: Oct 24 13:31:03 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand status took 3 milliseconds Oct 24 13:31:03 volumio volumio[1018]: info: ControllerMpd::getState Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand status took 1 milliseconds Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 24 13:31:03 volumio volumio[1018]: info: ControllerMpd::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paradise","artist":"DJ Snake, Bipolar Sunshine","album":"Paradise","uri":"NAS/MS-148/Super Hit - June 2025/DJ Snake/DJ Snake - Paradise.mp3","trackType":"mp3"} Oct 24 13:31:03 volumio volumio[1018]: verbose: CURRENT POSITION 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 24 13:31:03 volumio volumio[1018]: info: ------------------------------ 8ms Oct 24 13:31:03 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 24 13:31:03 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 24 13:31:03 volumio volumio[1018]: info: ControllerMpd::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paradise","artist":"DJ Snake, Bipolar Sunshine","album":"Paradise","uri":"NAS/MS-148/Super Hit - June 2025/DJ Snake/DJ Snake - Paradise.mp3","trackType":"mp3"} Oct 24 13:31:03 volumio volumio[1018]: verbose: CURRENT POSITION 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Oct 24 13:31:03 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 24 13:31:03 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 24 13:31:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 31 Oct 24 13:31:03 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 24 13:31:03 volumio volumio[1018]: info: ------------------------------ 10ms Oct 24 13:31:09 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 24 13:31:09 volumio volumio[1018]: info: CURURI: artists:// Oct 24 13:31:10 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/070%20Shake/c705dddb-38f6-481d-98b4-1501f62f7ae0.jpg' Oct 24 13:31:10 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/2Pac/898c9d01-bf51-48f6-aacd-c20e4e9bc3bf.jpg' Oct 24 13:31:16 volumio ntpd[661]: Soliciting pool server 240b:400d:3:3300:aeda:71da:9779:d4f1 Oct 24 13:31:18 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%40UpAhang/298a5e5c-894f-4f32-b91d-2d7dec58f591.jpg' Oct 24 13:31:20 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aha/f08cbf4b-ac2c-4b21-8d14-ed736e6c874a.jpg' Oct 24 13:31:20 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alan%20Walker/5c2af727-4488-47db-b247-3ea1fc8caf22.jpg' Oct 24 13:31:20 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Akon/3e36614d-475f-4b70-b56f-b5952683beff.jpg' Oct 24 13:31:21 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Amirali/25a71226-9d35-4d6e-a11a-dd81c6743ba3.jpeg' Oct 24 13:31:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 24 13:31:22 volumio volumio[1018]: info: CURURI: artists:// Oct 24 13:31:22 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alok/6dde7fa0-3951-4e77-84ab-5091939214b6.jpg' Oct 24 13:31:22 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/070%20Shake/c705dddb-38f6-481d-98b4-1501f62f7ae0.jpg' Oct 24 13:31:22 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/2Pac/898c9d01-bf51-48f6-aacd-c20e4e9bc3bf.jpg' Oct 24 13:31:24 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BLOND%3AISH/8e21e8eb-cdc4-4464-891e-9d5e16d504b2.jpeg' Oct 24 13:31:24 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%40UpAhang/298a5e5c-894f-4f32-b91d-2d7dec58f591.jpg' Oct 24 13:31:25 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Becky%20G/eefe34d9-4b85-4350-8ba3-5a412f0d95e1.jpg' Oct 24 13:31:28 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bob%20Sinclar/c653b91d-8cce-4e08-8d0d-95e7db9e73a2.jpg' Oct 24 13:31:28 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aha/f08cbf4b-ac2c-4b21-8d14-ed736e6c874a.jpg' Oct 24 13:31:28 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Akon/3e36614d-475f-4b70-b56f-b5952683beff.jpg' Oct 24 13:31:28 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alan%20Walker/5c2af727-4488-47db-b247-3ea1fc8caf22.jpg' Oct 24 13:31:28 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alok/6dde7fa0-3951-4e77-84ab-5091939214b6.jpg' Oct 24 13:31:29 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Amirali/25a71226-9d35-4d6e-a11a-dd81c6743ba3.jpeg' Oct 24 13:31:33 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BLOND%3AISH/8e21e8eb-cdc4-4464-891e-9d5e16d504b2.jpeg' Oct 24 13:31:33 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/DJ%20Snake/503d25fd-d1c0-477f-b487-10c64921d914.jpg' Oct 24 13:31:33 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Becky%20G/eefe34d9-4b85-4350-8ba3-5a412f0d95e1.jpg' Oct 24 13:31:35 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bob%20Sinclar/c653b91d-8cce-4e08-8d0d-95e7db9e73a2.jpg' Oct 24 13:31:39 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/DJ%20Snake/503d25fd-d1c0-477f-b487-10c64921d914.jpg' Oct 24 13:31:40 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dasha/58d7d803-9615-462f-84ce-b0ba3e856fce.jpg' Oct 24 13:31:40 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Daddy%20Yankee/71d63387-3ba4-4678-b2a7-d02e3dea4e44.jpg' Oct 24 13:31:46 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/David%20Vendetta/92308066-3e14-4400-802c-b3939e28e822.jpeg' Oct 24 13:31:49 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/DeepTurco/675b21d0-b7ac-4698-a905-e76a74b44add.jpg' Oct 24 13:31:49 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Daddy%20Yankee/71d63387-3ba4-4678-b2a7-d02e3dea4e44.jpg' Oct 24 13:31:50 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dasha/58d7d803-9615-462f-84ce-b0ba3e856fce.jpg' Oct 24 13:31:58 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/David%20Vendetta/92308066-3e14-4400-802c-b3939e28e822.jpeg' Oct 24 13:31:58 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/DeepTurco/675b21d0-b7ac-4698-a905-e76a74b44add.jpg' Oct 24 13:31:58 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dennis%20Cruz/b5377a32-eca6-4bfb-919f-e37441bc43d8.jpg' Oct 24 13:31:58 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dillon%20Francis/f6fbc30f-2bfe-43d5-bf80-0f4f01af30fb.jpg' Oct 24 13:32:01 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dr.%20Dre/65149b0f-50c4-4438-a002-23fa163b726e.jpg' Oct 24 13:32:01 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Flo%20Rida/59037093-541a-4f8d-afb9-ff81444070f2.jpg' Oct 24 13:32:01 volumio volumio[1018]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/El%20Profesor%2F%20HUGEL/55b8d626-2775-4afa-9963-b15078df233d.jpg' Oct 24 13:32:02 volumio volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 13:32:02 volumio volumio[1018]: Error: getaddrinfo ENOTFOUND cdn-images.dzcdn.net Oct 24 13:32:02 volumio volumio[1018]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Oct 24 13:32:02 volumio volumio[1018]: errno: -3007, Oct 24 13:32:02 volumio volumio[1018]: code: 'ENOTFOUND', Oct 24 13:32:02 volumio volumio[1018]: syscall: 'getaddrinfo', Oct 24 13:32:02 volumio volumio[1018]: hostname: 'cdn-images.dzcdn.net' Oct 24 13:32:02 volumio volumio[1018]: } Oct 24 13:32:02 volumio volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 13:32:02 volumio sudo[18833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 13:31 Oct 24 13:32:02 volumio sudo[18833]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:02 volumio sudo[18833]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:02 volumio volumio-remote-updater[472]: [2025-10-24 13:32:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 24 13:32:02 volumio volumio-remote-updater[472]: [2025-10-24 13:32:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 24 13:32:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 24 13:32:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 24 13:32:02 volumio systemd[1]: Started dynamicswap service. Oct 24 13:32:02 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 13:32:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 24 13:32:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Oct 24 13:32:02 volumio systemd[1]: Started dynamicswap service. Oct 24 13:32:02 volumio systemd[1]: Stopped Volumio Backend Module. Oct 24 13:32:02 volumio systemd[1]: Started Volumio Backend Module. Oct 24 13:32:02 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 13:32:03 volumio volumio[18845]: info: ------------------------------------------- Oct 24 13:32:03 volumio volumio[18845]: info: ----- Volumio3 ---- Oct 24 13:32:03 volumio volumio[18845]: info: ------------------------------------------- Oct 24 13:32:03 volumio volumio[18845]: info: ----- System startup ---- Oct 24 13:32:03 volumio volumio[18845]: info: ------------------------------------------- Oct 24 13:32:04 volumio volumio[18845]: info: MYVOLUMIO Environment detected Oct 24 13:32:04 volumio volumio[18845]: info: Plugin folders cleanup Oct 24 13:32:04 volumio volumio[18845]: info: Scanning into folder /volumio/app/plugins/ Oct 24 13:32:04 volumio volumio[18845]: info: Scanning category audio_interface Oct 24 13:32:04 volumio volumio[18845]: info: Scanning category miscellanea Oct 24 13:32:04 volumio volumio[18845]: info: Scanning category music_service Oct 24 13:32:04 volumio volumio[18845]: info: Scanning category plugins.json Oct 24 13:32:04 volumio volumio[18845]: info: Scanning category system_controller Oct 24 13:32:04 volumio volumio[18845]: info: Scanning category user_interface Oct 24 13:32:04 volumio volumio[18845]: info: Scanning into folder /data/plugins/ Oct 24 13:32:04 volumio volumio[18845]: info: Plugin folders cleanup completed Oct 24 13:32:04 volumio volumio[18845]: info: ------------------------------------------- Oct 24 13:32:04 volumio volumio[18845]: info: ----- Core plugins startup ---- Oct 24 13:32:04 volumio volumio[18845]: info: ------------------------------------------- Oct 24 13:32:04 volumio volumio[18845]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 13:32:04 volumio volumio[18845]: info: Adding plugin upnp to MyMusic Plugins Oct 24 13:32:04 volumio volumio[18845]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 13:32:04 volumio volumio[18845]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 13:32:04 volumio volumio[18845]: info: Loading plugins from folder /data/plugins/ Oct 24 13:32:04 volumio volumio[18845]: info: Loading plugin "system"... Oct 24 13:32:04 volumio volumio[18845]: info: Loading plugin "appearance"... Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "network"... Oct 24 13:32:05 volumio volumio[18845]: info: Refreshing Cached IP Addresses Oct 24 13:32:05 volumio sudo[18869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 13:32:05 volumio sudo[18869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "services"... Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "alsa_controller"... Oct 24 13:32:05 volumio sudo[18869]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:05 volumio sudo[18871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 13:32:05 volumio sudo[18871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:05 volumio sudo[18871]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "wizard"... Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "volumio_command_line_client"... Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "upnp"... Oct 24 13:32:05 volumio volumio[18845]: info: [1761312725277] Starting Upmpd Daemon Oct 24 13:32:05 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "my_music"... Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "mpd"... Oct 24 13:32:05 volumio volumio[18845]: info: Creating MPD Configuration file Oct 24 13:32:05 volumio sudo[18880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "upnp_browser"... Oct 24 13:32:05 volumio sudo[18880]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:05 volumio sudo[18880]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:05 volumio sudo[18882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 13:32:05 volumio sudo[18882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:05 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 13:32:05 volumio systemd[1]: mpd.service: Succeeded. Oct 24 13:32:05 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 13:32:05 volumio systemd[1]: Starting Music Player Daemon... Oct 24 13:32:05 volumio volumio[18845]: info: Loading plugin "networkfs"... Oct 24 13:32:05 volumio volumio[18845]: info: Cannot mount NAS MS-148 at system boot, trial number 1 ,retrying in 5 seconds Oct 24 13:32:05 volumio volumio[18845]: info: Starting Udev Watcher for removable devices Oct 24 13:32:06 volumio volumio[18845]: info: Ignoring mount for partition: boot Oct 24 13:32:06 volumio volumio[18845]: info: Ignoring mount for partition: volumio Oct 24 13:32:06 volumio volumio[18845]: info: Ignoring mount for partition: volumio_data Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "alarm-clock"... Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "airplay_emulation"... Oct 24 13:32:06 volumio volumio[18845]: info: Starting Shairport Sync Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "last_100"... Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "webradio"... Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "i2s_dacs"... Oct 24 13:32:06 volumio volumio[18845]: info: I2S DAC not set, start Auto-detection Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "volumiodiscovery"... Oct 24 13:32:06 volumio volumio[18845]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 13:32:06 volumio volumio[18845]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 13:32:06 volumio volumio[18845]: *** WARNING *** For more information see Oct 24 13:32:06 volumio node[18845]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 13:32:06 volumio volumio[18845]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 13:32:06 volumio volumio[18845]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 13:32:06 volumio volumio[18845]: *** WARNING *** For more information see Oct 24 13:32:06 volumio node[18845]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 13:32:06 volumio node[18845]: *** WARNING *** For more information see Oct 24 13:32:06 volumio node[18845]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 13:32:06 volumio node[18845]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 13:32:06 volumio node[18845]: *** WARNING *** For more information see Oct 24 13:32:06 volumio volumio[18845]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 13:32:06 volumio volumio[18845]: info: Discovery: Started advertising with name: Volumio Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "outputs"... Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "albumart"... Oct 24 13:32:06 volumio volumio[18845]: info: Plugin example_plugin is not enabled Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "inputs"... Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "updater_comm"... Oct 24 13:32:06 volumio volumio[18845]: info: Plugin mpdemulation is not enabled Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "rest_api"... Oct 24 13:32:06 volumio volumio[18845]: info: Loading plugin "websocket"... Oct 24 13:32:06 volumio volumio[18845]: info: Loading i18n strings for locale en Oct 24 13:32:06 volumio volumio[18845]: Updating browse sources language Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::initPlayerControls Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: Express server listening on port 3000 Oct 24 13:32:06 volumio volumio[18845]: [Metrics] WebUI: 3s 37.27ms Oct 24 13:32:06 volumio volumio[18845]: info: CoreStateMachine::resetVolumioState Oct 24 13:32:06 volumio volumio[18845]: info: CoreStateMachine::getcurrentVolume Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 13:32:06 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:32:06 volumio volumio[18845]: Forking 3 albumart workers Oct 24 13:32:06 volumio volumio[18845]: info: MPD Permissions set Oct 24 13:32:06 volumio mpd[18887]: Oct 24 13:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 13:32:07 volumio mpd[18887]: Oct 24 13:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 24 13:32:07 volumio mpd[18887]: Oct 24 13:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 24 13:32:07 volumio systemd[1]: Started Music Player Daemon. Oct 24 13:32:07 volumio sudo[18882]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: Reloading queue from file Oct 24 13:32:07 volumio volumio[18845]: info: VolumeController:: Volume=20 Mute =false Oct 24 13:32:07 volumio volumio[18845]: info: CoreStateMachine::pushState Oct 24 13:32:07 volumio volumio[18845]: info: CorePlayQueue::getTrack 0 Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::volumioPushState Oct 24 13:32:07 volumio volumio[18845]: info: CoreStateMachine::updateTrackBlock Oct 24 13:32:07 volumio volumio[18845]: info: CorePlayQueue::getTrackBlock Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 13:32:07 volumio volumio[18845]: info: CoreStateMachine::setRepeat false single undefined Oct 24 13:32:07 volumio volumio[18845]: info: CoreStateMachine::pushState Oct 24 13:32:07 volumio volumio[18845]: info: CorePlayQueue::getTrack 0 Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::volumioPushState Oct 24 13:32:07 volumio volumio[18845]: info: CoreStateMachine::setRandom false Oct 24 13:32:07 volumio volumio[18845]: info: CoreStateMachine::pushState Oct 24 13:32:07 volumio volumio[18845]: info: CorePlayQueue::getTrack 0 Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::volumioPushState Oct 24 13:32:07 volumio volumio[18845]: info: Setting Device type: Raspberry PI Oct 24 13:32:07 volumio volumio[18845]: info: MPD running with PID18887 Oct 24 13:32:07 volumio volumio[18845]: ,establishing connection Oct 24 13:32:07 volumio volumio[18845]: info: Completed loading Core Plugins Oct 24 13:32:07 volumio volumio[18845]: info: Preparing to generate the ALSA configuration file Oct 24 13:32:07 volumio volumio[18845]: error: updateQueue error: null Oct 24 13:32:07 volumio volumio[18845]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 24 13:32:07 volumio volumio[18845]: Starting albumart workers Oct 24 13:32:07 volumio volumio[18845]: Starting albumart workers Oct 24 13:32:07 volumio volumio[18845]: Starting albumart workers Oct 24 13:32:07 volumio volumio-remote-updater[472]: [2025-10-24 13:32:07] [connect] Successful connection Oct 24 13:32:07 volumio volumio[18845]: info: VolumeController:: Volume=20 Mute =false Oct 24 13:32:07 volumio volumio[18845]: info: CoreStateMachine::pushState Oct 24 13:32:07 volumio volumio[18845]: info: CorePlayQueue::getTrack 0 Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::volumioPushState Oct 24 13:32:07 volumio volumio[18845]: error: updateQueue error: null Oct 24 13:32:07 volumio volumio-remote-updater[472]: [2025-10-24 13:32:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761312727 101 Oct 24 13:32:07 volumio volumio[18845]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Oct 24 13:32:07 volumio volumio[18845]: info: Asound.conf file unchanged, so no further update is needed Oct 24 13:32:07 volumio volumio[18845]: info: Output device has changed, restarting MPD Oct 24 13:32:07 volumio sudo[18948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 13:32:07 volumio volumio[18845]: info: Output device has changed, restarting Shairport Sync Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:07 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:32:07 volumio sudo[18948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:07 volumio sudo[18948]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:07 volumio sudo[18950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 13:32:07 volumio sudo[18950]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:07 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 13:32:07 volumio volumio[18845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 13:32:07 volumio systemd[1]: mpd.service: Succeeded. Oct 24 13:32:07 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 13:32:07 volumio volumio[18845]: info: ___________ START PLUGINS ___________ Oct 24 13:32:07 volumio systemd[1]: Starting Music Player Daemon... Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 13:32:08 volumio volumio[18845]: info: [1761312728005] CoreMusicLibrary::Adding element Media Servers Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 13:32:08 volumio volumio[18845]: Cannot find translation for sourceMedia Servers Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 13:32:08 volumio volumio[18845]: info: [1761312728132] CoreMusicLibrary::Adding element Last_100 Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 13:32:08 volumio volumio[18845]: Cannot find translation for sourceMedia Servers Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 13:32:08 volumio volumio[18845]: info: [1761312728135] CoreMusicLibrary::Adding element Webradio Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 13:32:08 volumio volumio[18845]: Cannot find translation for sourceMedia Servers Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 13:32:08 volumio volumio[18845]: info: Volumio Calling Home Oct 24 13:32:08 volumio volumio[18845]: info: Completed starting Core Plugins Oct 24 13:32:08 volumio volumio[18845]: info: ------------------------------------------- Oct 24 13:32:08 volumio volumio[18845]: info: ----- MyVolumio plugins startup ---- Oct 24 13:32:08 volumio volumio[18845]: info: ------------------------------------------- Oct 24 13:32:08 volumio volumio[18845]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 24 13:32:08 volumio volumio[18845]: info: MPD Permissions set Oct 24 13:32:08 volumio volumio[18845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:32:08 volumio volumio[18845]: info: Starting Shairport Sync Oct 24 13:32:08 volumio volumio[18845]: info: Starting Shairport Sync Oct 24 13:32:08 volumio sudo[18989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 13:32:08 volumio volumio[18845]: info: Starting Shairport Sync Oct 24 13:32:08 volumio sudo[18989]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:08 volumio sudo[18991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 13:32:08 volumio sudo[18991]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 13:32:08 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 24 13:32:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 13:32:08 volumio sudo[18995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 13:32:08 volumio sudo[18995]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 13:32:08 volumio sudo[18989]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 13:32:08 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 24 13:32:08 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 24 13:32:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 13:32:08 volumio volumio[18845]: info: Shairport-Sync Started Oct 24 13:32:08 volumio volumio[18845]: Error adding Membership: Error: addMembership EINVAL Oct 24 13:32:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 13:32:08 volumio volumio[18845]: info: Shairport-Sync Started Oct 24 13:32:08 volumio sudo[18991]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:08 volumio sudo[18995]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:08 volumio volumio[18845]: info: Shairport-Sync Started Oct 24 13:32:08 volumio mpd[18980]: Oct 24 13:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 13:32:08 volumio mpd[18980]: Oct 24 13:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 24 13:32:08 volumio mpd[18980]: Oct 24 13:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 24 13:32:08 volumio systemd[1]: Started Music Player Daemon. Oct 24 13:32:08 volumio sudo[18950]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:08 volumio volumio[18845]: error: updateQueue error: null Oct 24 13:32:10 volumio volumio[18845]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Serhat%20Durmus/0f983b1d-ae0c-4ea4-b79b-7538225fd7ba.jpg' Oct 24 13:32:10 volumio volumio[18845]: info: Cannot mount NAS MS-148 at system boot, trial number 2 ,retrying in 5 seconds Oct 24 13:32:11 volumio volumio[18845]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/SOOL%20MUSIC/eab6856e-a994-4659-b0fb-43e057f06e56.jpg' Oct 24 13:32:12 volumio volumio[18845]: info: Volumio called home Oct 24 13:32:15 volumio sudo[19005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 13:32:15 volumio sudo[19005]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:15 volumio sudo[19005]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:15 volumio sudo[19008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 13:32:15 volumio sudo[19008]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:15 volumio sudo[19008]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:15 volumio sudo[19011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 24 13:32:15 volumio sudo[19011]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:32:15 volumio sudo[19011]: pam_unix(sudo:session): session closed for user root Oct 24 13:32:15 volumio volumio[18845]: info: Upmpdcli Daemon Started Oct 24 13:32:15 volumio volumio[18845]: info: Discovery: adding 2c24a74e-985a-434b-92c0-e65955befbb9 Oct 24 13:32:15 volumio volumio[18845]: info: mDNS: Found device Volumio Oct 24 13:32:15 volumio volumio[18845]: info: CoreCommandRouter::volumioGetState Oct 24 13:32:15 volumio volumio[18845]: info: CorePlayQueue::getTrack 0 Oct 24 13:32:15 volumio volumio[18845]: info: Cannot mount NAS MS-148 at system boot, trial number 3 ,retrying in 5 seconds Oct 24 13:32:19 volumio volumio[18845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 13:32:19 volumio volumio[18845]: Error: connect EHOSTUNREACH 10.10.34.36:80 Oct 24 13:32:19 volumio volumio[18845]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 24 13:32:19 volumio volumio[18845]: errno: -113, Oct 24 13:32:19 volumio volumio[18845]: code: 'EHOSTUNREACH', Oct 24 13:32:19 volumio volumio[18845]: syscall: 'connect', Oct 24 13:32:19 volumio volumio[18845]: address: '10.10.34.36', Oct 24 13:32:19 volumio volumio[18845]: port: 80 Oct 24 13:32:19 volumio volumio[18845]: } Oct 24 13:32:19 volumio volumio[18845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 13:32:19 volumio sudo[19022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 13:31 Oct 24 13:32:19 volumio sudo[19022]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"