Oct 03 17:06:02 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:02.266+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s Oct 03 17:06:02 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:02 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:02.828+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=562.285662ms Oct 03 17:06:02 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:02.838+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=http://pushupdates.volumio.org duration=571.910522ms Oct 03 17:06:02 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:02.852+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=585.996584ms Oct 03 17:06:02 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:02.984+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=717.339291ms Oct 03 17:06:03 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:03.184+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=http://cddb.volumio.org duration=917.930704ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 17:06:03 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:03.223+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=956.835257ms Oct 03 17:06:03 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:03.550+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://database.volumio.cloud duration=1.283795723s Oct 03 17:06:03 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:03.734+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.468004792s Oct 03 17:06:04 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:04.513+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://functions.volumio.cloud duration=2.246879183s Oct 03 17:06:05 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 17:06:05 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 17:06:05 localhost volumio[1192]: info: Discovery: Getting this device information Oct 03 17:06:05 localhost volumio[1192]: info: CoreCommandRouter::volumioGetState Oct 03 17:06:05 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:05 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 17:06:05 localhost volumio[1192]: verbose: New Socket.io Connection to 192.168.3.83:3000 from 192.168.3.77 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 03 17:06:05 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 17:06:05 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 17:06:12 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:12.266+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://securetoken.googleapis.com duration=10.0006924s error="Head \"https://securetoken.googleapis.com\": context deadline exceeded" Oct 03 17:06:12 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:12.266+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=http://plugins.volumio.org duration=10.000106427s error="Head \"http://plugins.volumio.workers.dev/\": context deadline exceeded" Oct 03 17:06:12 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:12.266+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://www.googleapis.com duration=10.000878066s error="Head \"https://www.googleapis.com\": context deadline exceeded" Oct 03 17:06:12 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:12.267+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-2.000982ms timeout=10s endpoint=https://google.com duration=10.000802903s error="Head \"https://google.com\": context deadline exceeded" Oct 03 17:06:16 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:17 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:17.486+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s Oct 03 17:06:17 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 17:06:17 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 17:06:17 localhost volumio[1192]: info: Discovery: Getting this device information Oct 03 17:06:17 localhost volumio[1192]: info: CoreCommandRouter::volumioGetState Oct 03 17:06:17 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:17 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 17:06:17 localhost volumio[1192]: verbose: New Socket.io Connection to 192.168.3.83:3000 from 192.168.3.77 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 03 17:06:17 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 17:06:17 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 17:06:17 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:17.660+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=174.3298ms Oct 03 17:06:17 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:17.675+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=188.864822ms Oct 03 17:06:17 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:17.716+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=230.132139ms Oct 03 17:06:17 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:17.734+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://functions.volumio.cloud duration=247.233292ms Oct 03 17:06:17 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:17.734+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://functions.volumio.cloud duration=247.489438ms Oct 03 17:06:17 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:17.765+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=http://pushupdates.volumio.org duration=278.906777ms Oct 03 17:06:17 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:17.837+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://myvolumio.firebaseio.com duration=350.549833ms Oct 03 17:06:18 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:18.302+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=http://cddb.volumio.org duration=816.024562ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 17:06:20 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:22 localhost sudo[12605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 17:06:22 localhost sudo[12607]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 17:06:22 localhost sudo[12607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 17:06:22 localhost sudo[12605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 17:06:22 localhost sudo[12607]: pam_unix(sudo:session): session closed for user root Oct 03 17:06:22 localhost sudo[12605]: pam_unix(sudo:session): session closed for user root Oct 03 17:06:22 localhost volumio[1192]: verbose: New Socket.io Connection to 192.168.3.83 from 192.168.3.77 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 03 17:06:22 localhost volumio[1192]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 17:06:22 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 17:06:22 localhost volumio[1192]: info: CoreCommandRouter::volumioGetState Oct 03 17:06:22 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:22 localhost volumio[1192]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:06:22 localhost volumio[1192]: info: CoreStateMachine::getQueue Oct 03 17:06:22 localhost volumio[1192]: info: CorePlayQueue::getQueue Oct 03 17:06:22 localhost volumio[1192]: info: Listing playlists Oct 03 17:06:22 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 03 17:06:22 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 17:06:22 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 17:06:22 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 17:06:23 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:23.902+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://database.volumio.cloud duration=6.415978381s Oct 03 17:06:24 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:27 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:27.487+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=http://plugins.volumio.org duration=10.000736482s error="Head \"http://plugins.volumio.workers.dev/\": context deadline exceeded" Oct 03 17:06:27 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:27.487+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://www.googleapis.com duration=10.000569763s error="Head \"https://www.googleapis.com\": context deadline exceeded" Oct 03 17:06:27 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:27.487+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://securetoken.googleapis.com duration=10.000658515s error="Head \"https://securetoken.googleapis.com\": context deadline exceeded" Oct 03 17:06:27 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:27.487+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.77:62584 @ 0xc0004a2e40" latency=-553.358µs timeout=10s endpoint=https://google.com duration=10.000902275s error="Head \"https://google.com\": context deadline exceeded" Oct 03 17:06:27 localhost volumio[1192]: info: MPD Database updated - AlbumList cache refreshed Oct 03 17:06:27 localhost volumio[1192]: info: Oct 03 17:06:27 localhost volumio[1192]: ---------------------------- MPD announces state update: database Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:27 localhost volumio[1192]: info: Oct 03 17:06:27 localhost volumio[1192]: ---------------------------- MPD announces state update: update Oct 03 17:06:27 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:27 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:27 localhost volumio[1192]: info: MPD Database updated - AlbumList cache refreshed Oct 03 17:06:27 localhost volumio[1192]: info: Oct 03 17:06:27 localhost volumio[1192]: ---------------------------- MPD announces state update: database Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:27 localhost volumio[1192]: info: Oct 03 17:06:27 localhost volumio[1192]: ---------------------------- MPD announces state update: update Oct 03 17:06:27 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:27 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:27 localhost volumio[1192]: info: MPD Database updated - AlbumList cache refreshed Oct 03 17:06:27 localhost volumio[1192]: info: Oct 03 17:06:27 localhost volumio[1192]: ---------------------------- MPD announces state update: database Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:27 localhost volumio[1192]: info: Oct 03 17:06:27 localhost volumio[1192]: ---------------------------- MPD announces state update: update Oct 03 17:06:27 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:27 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:27 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 235 milliseconds Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 235 milliseconds Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 235 milliseconds Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: info: Command Router : Notfying DB Updatetrue Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::Close All Modals sent Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:28 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: No code Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:28 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: No code Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: ------------------------------ 262ms Oct 03 17:06:28 localhost volumio[1192]: info: ------------------------------ 262ms Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.169+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.178+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.179+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.169+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.179+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.179+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.179+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.179+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.169+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.180+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.169+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.181+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 368 milliseconds Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 369 milliseconds Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 369 milliseconds Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: info: Command Router : Notfying DB Updatetrue Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::Close All Modals sent Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:28 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: No code Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:28 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: No code Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio[1192]: info: ------------------------------ 386ms Oct 03 17:06:28 localhost volumio[1192]: info: ------------------------------ 386ms Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 479 milliseconds Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 479 milliseconds Oct 03 17:06:28 localhost volumio[1192]: info: sendMpdCommand status took 479 milliseconds Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: info: Command Router : Notfying DB Updatetrue Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::Close All Modals sent Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:28 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:28 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: No code Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:28 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio[1192]: info: No code Oct 03 17:06:28 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:28 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.404+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.404+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.404+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.405+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.405+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.405+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.405+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.405+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio[1192]: info: ------------------------------ 494ms Oct 03 17:06:28 localhost volumio[1192]: info: ------------------------------ 494ms Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.407+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.407+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.407+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:28 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:28.407+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:28 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:32 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:35 localhost volumio[1192]: info: MPD Database updated - AlbumList cache refreshed Oct 03 17:06:35 localhost volumio[1192]: info: Oct 03 17:06:35 localhost volumio[1192]: ---------------------------- MPD announces state update: database Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: Oct 03 17:06:35 localhost volumio[1192]: ---------------------------- MPD announces state update: update Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: MPD Database updated - AlbumList cache refreshed Oct 03 17:06:35 localhost volumio[1192]: info: Oct 03 17:06:35 localhost volumio[1192]: ---------------------------- MPD announces state update: database Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: Oct 03 17:06:35 localhost volumio[1192]: ---------------------------- MPD announces state update: update Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: MPD Database updated - AlbumList cache refreshed Oct 03 17:06:35 localhost volumio[1192]: info: Oct 03 17:06:35 localhost volumio[1192]: ---------------------------- MPD announces state update: database Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: Oct 03 17:06:35 localhost volumio[1192]: ---------------------------- MPD announces state update: update Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 276 milliseconds Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 276 milliseconds Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 276 milliseconds Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:35 localhost volumio[1192]: info: Command Router : Notfying DB Updatefalse Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::Close All Modals sent Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:35 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: No code Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:35 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: No code Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.740+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.740+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.740+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.740+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.741+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.741+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.741+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.741+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio[1192]: info: ------------------------------ 302ms Oct 03 17:06:35 localhost volumio[1192]: info: ------------------------------ 302ms Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 441 milliseconds Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 441 milliseconds Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 441 milliseconds Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:35 localhost volumio[1192]: info: Command Router : Notfying DB Updatefalse Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::Close All Modals sent Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:35 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: No code Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:35 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio[1192]: info: No code Oct 03 17:06:35 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:35 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:35 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.901+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.902+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.902+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:35 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:35.902+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:35 localhost volumio[1192]: info: ------------------------------ 462ms Oct 03 17:06:35 localhost volumio[1192]: info: ------------------------------ 462ms Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 549 milliseconds Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 549 milliseconds Oct 03 17:06:35 localhost volumio[1192]: info: sendMpdCommand status took 549 milliseconds Oct 03 17:06:35 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:36 localhost volumio[1192]: info: Command Router : Notfying DB Updatefalse Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::Close All Modals sent Oct 03 17:06:36 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:36 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:06:36 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:36 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:36 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:36 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:36 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:36 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:36 localhost volumio[1192]: info: No code Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:36 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:36 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:36 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:36 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:36 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:06:36 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:36 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:36 localhost volumio[1192]: info: No code Oct 03 17:06:36 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:06:36 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.012+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.015+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.015+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.015+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.015+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.015+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.015+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.013+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.016+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.015+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.015+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:06:36 localhost volumio5-onboarding[1608]: time=2026-10-03T17:06:36.016+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id= title= Oct 03 17:06:36 localhost volumio[1192]: info: ------------------------------ 577ms Oct 03 17:06:36 localhost volumio[1192]: info: ------------------------------ 577ms Oct 03 17:06:36 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:40 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:44 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:48 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:52 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:06:56 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:00 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:04 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:07 localhost nmbd[1162]: [2026/10/03 17:07:07.427266, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 03 17:07:07 localhost nmbd[1162]: register_name_response: Answer name <00> differs from question name <00>. Oct 03 17:07:07 localhost nmbd[1162]: [2026/10/03 17:07:07.427422, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 03 17:07:07 localhost nmbd[1162]: register_name_response: Answer name <03> differs from question name <03>. Oct 03 17:07:07 localhost nmbd[1162]: [2026/10/03 17:07:07.427514, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 03 17:07:07 localhost nmbd[1162]: register_name_response: Answer name <20> differs from question name <20>. Oct 03 17:07:08 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:12 localhost systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 03 17:07:12 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:16 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:17 localhost systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 03 17:07:17 localhost systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 03 17:07:20 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:24 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:28 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:32 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 17:07:36 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 03 17:07:36 localhost volumio[1192]: info: CURURI: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF) Oct 03 17:07:36 localhost volumio[1192]: info: Preload queue cleared Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/02.懷念戰友.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/04.曲蔓地.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/05.歌唱二小放牛郎.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/06.鼓浪嶼之波.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/07.革命人永遠是年輕.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/08.草原上升起不落的太陽.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/09.漁光曲.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/10.長城謠.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/11.春天里.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/12.可愛的一朵玫瑰花.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/13.翻身農奴把歌唱.dsf Oct 03 17:07:36 localhost volumio[1192]: info: Preloading song: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/14.難忘今宵.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F01.%E9%95%B7%E4%BA%AD%E5%A4%96.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/02.懷念戰友.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F02.%E6%87%B7%E5%BF%B5%E6%88%B0%E5%8F%8B.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/02.懷念戰友.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F03.%E7%A7%8B%E6%B0%B4%E4%BC%8A%E4%BA%BA.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/04.曲蔓地.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F04.%E6%9B%B2%E8%94%93%E5%9C%B0.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/04.曲蔓地.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/05.歌唱二小放牛郎.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F05.%E6%AD%8C%E5%94%B1%E4%BA%8C%E5%B0%8F%E6%94%BE%E7%89%9B%E9%83%8E.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/05.歌唱二小放牛郎.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/06.鼓浪嶼之波.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F06.%E9%BC%93%E6%B5%AA%E5%B6%BC%E4%B9%8B%E6%B3%A2.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/06.鼓浪嶼之波.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/07.革命人永遠是年輕.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F07.%E9%9D%A9%E5%91%BD%E4%BA%BA%E6%B0%B8%E9%81%A0%E6%98%AF%E5%B9%B4%E8%BC%95.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/07.革命人永遠是年輕.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/08.草原上升起不落的太陽.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F08.%E8%8D%89%E5%8E%9F%E4%B8%8A%E5%8D%87%E8%B5%B7%E4%B8%8D%E8%90%BD%E7%9A%84%E5%A4%AA%E9%99%BD.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/08.草原上升起不落的太陽.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/09.漁光曲.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F09.%E6%BC%81%E5%85%89%E6%9B%B2.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/09.漁光曲.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/10.長城謠.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F10.%E9%95%B7%E5%9F%8E%E8%AC%A0.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/10.長城謠.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/11.春天里.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F11.%E6%98%A5%E5%A4%A9%E9%87%8C.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/11.春天里.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/12.可愛的一朵玫瑰花.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F12.%E5%8F%AF%E6%84%9B%E7%9A%84%E4%B8%80%E6%9C%B5%E7%8E%AB%E7%91%B0%E8%8A%B1.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/12.可愛的一朵玫瑰花.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/13.翻身農奴把歌唱.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F13.%E7%BF%BB%E8%BA%AB%E8%BE%B2%E5%A5%B4%E6%8A%8A%E6%AD%8C%E5%94%B1.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/13.翻身農奴把歌唱.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Exploding uri music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/14.難忘今宵.dsf in service mpd Oct 03 17:07:37 localhost volumio[1192]: info: ALBUMART /albumart?cacheid=276&web=Gu%20Shuan/Melody%20Of%20The%20Land%20(made%20in%20USA)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FDSD%2F%E5%8F%A4%E7%92%87%20%E7%92%87%E6%9B%B2%E8%94%93%E5%9C%B0(DSF)%2F14.%E9%9B%A3%E5%BF%98%E4%BB%8A%E5%AE%B5.dsf&metadata=false Oct 03 17:07:37 localhost volumio[1192]: info: URI /mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/14.難忘今宵.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Preload queue cleared Oct 03 17:07:37 localhost volumio[1192]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::ClearQueue Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::stop Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::clearPlayQueue Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::saveQueue Oct 03 17:07:37 localhost volumio[1192]: info: CoreCommandRouter::volumioPushQueue Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::addQueueItems Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::addQueueItems Oct 03 17:07:37 localhost volumio[1192]: info: Preload queue cleared Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf Oct 03 17:07:37 localhost volumio[1192]: info: CoreCommandRouter::volumioPushQueue Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::saveQueue Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::updateTrackBlock Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::getTrackBlock Oct 03 17:07:37 localhost volumio[1192]: info: CoreCommandRouter::volumioPlay Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::play index 0 Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::addQueueItems Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::addQueueItems Oct 03 17:07:37 localhost volumio[1192]: info: Preload queue cleared Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/02.懷念戰友.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/02.懷念戰友.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/04.曲蔓地.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/04.曲蔓地.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/05.歌唱二小放牛郎.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/05.歌唱二小放牛郎.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/06.鼓浪嶼之波.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/06.鼓浪嶼之波.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/07.革命人永遠是年輕.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/07.革命人永遠是年輕.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/08.草原上升起不落的太陽.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/08.草原上升起不落的太陽.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/09.漁光曲.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/09.漁光曲.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/10.長城謠.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/10.長城謠.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/11.春天里.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/11.春天里.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/12.可愛的一朵玫瑰花.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/12.可愛的一朵玫瑰花.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/13.翻身農奴把歌唱.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/13.翻身農奴把歌唱.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/14.難忘今宵.dsf Oct 03 17:07:37 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/14.難忘今宵.dsf Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::stop Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:07:37 localhost volumio[1192]: info: CoreCommandRouter::volumioPushQueue Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::saveQueue Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::play index undefined Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::updateTrackBlock Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::getTrackBlock Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:37 localhost volumio[1192]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:07:37 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:37 localhost volumio[1192]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf Oct 03 17:07:37 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 17:07:37 localhost volumio[1192]: info: sendMpdCommand stop took 6 milliseconds Oct 03 17:07:37 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 17:07:37 localhost volumio[1192]: info: Oct 03 17:07:37 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:37 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:37 localhost volumio[1192]: info: sendMpdCommand clear took 2 milliseconds Oct 03 17:07:37 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf" Oct 03 17:07:37 localhost volumio[1192]: info: Oct 03 17:07:37 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:37 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:37 localhost volumio[1192]: info: Oct 03 17:07:37 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:37 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:37 localhost volumio[1192]: error: updateQueue error: null Oct 03 17:07:37 localhost volumio[1192]: info: Oct 03 17:07:37 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:37 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:37 localhost volumio[1192]: info: ------------------------------ 6ms Oct 03 17:07:37 localhost volumio[1192]: info: sendMpdCommand add "NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf" took 4 milliseconds Oct 03 17:07:37 localhost volumio[1192]: info: ------------------------------ 5ms Oct 03 17:07:37 localhost volumio[1192]: info: ------------------------------ 4ms Oct 03 17:07:37 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand play Oct 03 17:07:37 localhost volumio[1192]: info: Oct 03 17:07:37 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:37 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:37 localhost volumio[1192]: info: Oct 03 17:07:37 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:37 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:37 localhost volumio[1192]: info: ------------------------------ 4ms Oct 03 17:07:37 localhost volumio[1192]: info: sendMpdCommand play took 101 milliseconds Oct 03 17:07:37 localhost volumio[1192]: info: ------------------------------ 101ms Oct 03 17:07:37 localhost volumio[1192]: info: ------------------------------ 101ms Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 33 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 35 milliseconds Oct 03 17:07:38 localhost volumio[1192]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 36 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand clearerror took 4 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 3 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Outside The Pavilion","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.112+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=2 volume=100 Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 44ms Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.112+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf" title="Outside The Pavilion" Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 15 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand clearerror took 14 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand clearerror took 13 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 8 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Outside The Pavilion","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Outside The Pavilion","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Outside The Pavilion","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.135+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=2 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.135+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=2 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.136+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf" title="Outside The Pavilion" Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 68ms Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 68ms Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 35ms Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.136+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf" title="Outside The Pavilion" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.136+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=2 volume=100 Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.138+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf" title="Outside The Pavilion" Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Outside The Pavilion","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Outside The Pavilion","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 0 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.142+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=269 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.142+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf" title="Outside The Pavilion" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.143+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=269 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.143+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf" title="Outside The Pavilion" Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 41ms Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 31ms Oct 03 17:07:38 localhost volumio[1192]: info: Preload queue cleared Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::ClearQueue Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::clearPlayQueue Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::saveQueue Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushQueue Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::addQueueItems Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::addQueueItems Oct 03 17:07:38 localhost volumio[1192]: info: Preload queue cleared Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/01.長亭外.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/02.懷念戰友.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/02.懷念戰友.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushQueue Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::saveQueue Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::updateTrackBlock Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrackBlock Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPlay Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::play index 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::addQueueItems Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::addQueueItems Oct 03 17:07:38 localhost volumio[1192]: info: Preload queue cleared Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/04.曲蔓地.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/04.曲蔓地.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/05.歌唱二小放牛郎.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/05.歌唱二小放牛郎.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/06.鼓浪嶼之波.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/06.鼓浪嶼之波.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/07.革命人永遠是年輕.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/07.革命人永遠是年輕.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/08.草原上升起不落的太陽.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/08.草原上升起不落的太陽.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/09.漁光曲.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/09.漁光曲.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/10.長城謠.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/10.長城謠.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/11.春天里.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/11.春天里.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/12.可愛的一朵玫瑰花.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/12.可愛的一朵玫瑰花.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/13.翻身農奴把歌唱.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/13.翻身農奴把歌唱.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Adding Item to queue: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/14.難忘今宵.dsf Oct 03 17:07:38 localhost volumio[1192]: info: Using cached record of: music-library/NAS/music/DSD/古璇 璇曲蔓地(DSF)/14.難忘今宵.dsf Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushQueue Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::saveQueue Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::play index undefined Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::updateTrackBlock Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrackBlock Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand stop took 2 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:38 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 0 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand clear took 0 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: error: updateQueue error: null Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:38 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 1ms Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand add "NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" took 1 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 0 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 0 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand play Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: No code Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: No code Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 8ms Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.398+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.399+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.398+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.399+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.399+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.399+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.399+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.399+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.399+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.399+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.400+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.400+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 13ms Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 13ms Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:38 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces system playlist update Oct 03 17:07:38 localhost volumio[1192]: info: Ignoring MPD Status Update Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 14ms Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand play took 13 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 6ms Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 5ms Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 3 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: info: Oct 03 17:07:38 localhost volumio[1192]: ---------------------------- MPD announces state update: player Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::getState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:07:38 localhost volumio[1192]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 6 milliseconds Oct 03 17:07:38 localhost volumio[1192]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 6 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand clearerror took 5 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 4 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 3 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":319,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cowboy's Song","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.447+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.447+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 14ms Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand status took 10 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand clearerror took 8 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand clearerror took 7 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseState Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":319,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cowboy's Song","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":319,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cowboy's Song","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":319,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cowboy's Song","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":319,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cowboy's Song","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.455+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.455+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.456+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.456+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.456+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.456+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.456+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.456+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 23ms Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 22ms Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 22ms Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 19ms Oct 03 17:07:38 localhost volumio[1192]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 03 17:07:38 localhost volumio[1192]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:07:38 localhost volumio[1192]: info: ControllerMpd::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::servicePushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":319,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cowboy's Song","artist":"Gu Shuan","album":"Melody Of The Land (made in USA)","uri":"NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf","trackType":"dsf"} Oct 03 17:07:38 localhost volumio[1192]: verbose: CURRENT POSITION 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState stateService pause Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:07:38 localhost volumio[1192]: info: CoreStateMachine::pushState Oct 03 17:07:38 localhost volumio[1192]: info: CorePlayQueue::getTrack 2 Oct 03 17:07:38 localhost volumio[1192]: info: CoreCommandRouter::volumioPushState Oct 03 17:07:38 localhost volumio[1192]: info: ------------------------------ 23ms Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.466+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 17:07:38 localhost volumio5-onboarding[1608]: time=2026-10-03T17:07:38.466+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.77:62584 @ 0xc0004a2e40" id="mnt/NAS/music/DSD/古璇 璇曲蔓地(DSF)/03.秋水伊人.dsf" title="Cowboy's Song" Oct 03 17:07:39 localhost volumio[1192]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 17:07:39 localhost volumio[1192]: Error: connect ECONNREFUSED 157.240.9.36:443 Oct 03 17:07:39 localhost volumio[1192]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 03 17:07:39 localhost volumio[1192]: errno: -111, Oct 03 17:07:39 localhost volumio[1192]: code: 'ECONNREFUSED', Oct 03 17:07:39 localhost volumio[1192]: syscall: 'connect', Oct 03 17:07:39 localhost volumio[1192]: address: '157.240.9.36', Oct 03 17:07:39 localhost volumio[1192]: port: 443 Oct 03 17:07:39 localhost volumio[1192]: } Oct 03 17:07:39 localhost volumio[1192]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 17:07:39 localhost sudo[12868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 17:06' Oct 03 17:07:39 localhost sudo[12868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"