-- Logs begin at Sat 2026-03-28 20:30:55 CST, end at Sat 2026-03-28 20:41:37 CST. -- Mar 28 20:40:29 volumio-x86-784 volumio[3930]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 28 20:40:29 volumio-x86-784 volumio[3930]: info: Retrying Login Due to Network Error Mar 28 20:40:39 volumio-x86-784 volumio[3930]: info: MyVolumio login type: Token Mar 28 20:40:52 volumio-x86-784 volumio[3930]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: LoungeScreenDisconnected { Mar 28 20:40:52 volumio-x86-784 volumio[3930]: AID: null, Mar 28 20:40:52 volumio-x86-784 volumio[3930]: name: 'loungeScreenDisconnected', Mar 28 20:40:52 volumio-x86-784 volumio[3930]: payload: {} Mar 28 20:40:52 volumio-x86-784 volumio[3930]: } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Stack trace: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: IncompleteAPIDataError: Missing data required to construct query string from bind params Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at new Promise () Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at async Promise.all (index 1) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: error: [yt-cast-receiver] Failed to start receiver: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: (AppError) Failed to start YouTubeApp Mar 28 20:40:52 volumio-x86-784 volumio[3930]: --->(SessionError) (YouTube Music) Failed to establish session Mar 28 20:40:52 volumio-x86-784 volumio[3930]: ------>(ConnectionError) (YouTube Music) Connection error in generating screen Id Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Error info: { Mar 28 20:40:52 volumio-x86-784 volumio[3930]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Mar 28 20:40:52 volumio-x86-784 volumio[3930]: } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: connect ETIMEDOUT 31.13.92.37:443 Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Stack trace: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: AppError: Failed to start YouTubeApp Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: LoungeScreenDisconnected { Mar 28 20:40:52 volumio-x86-784 volumio[3930]: AID: null, Mar 28 20:40:52 volumio-x86-784 volumio[3930]: name: 'loungeScreenDisconnected', Mar 28 20:40:52 volumio-x86-784 volumio[3930]: payload: {} Mar 28 20:40:52 volumio-x86-784 volumio[3930]: } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Stack trace: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: IncompleteAPIDataError: Missing data required to construct query string from bind params Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at new Promise () Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Array.forEach () Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: LoungeScreenDisconnected { Mar 28 20:40:52 volumio-x86-784 volumio[3930]: AID: null, Mar 28 20:40:52 volumio-x86-784 volumio[3930]: name: 'loungeScreenDisconnected', Mar 28 20:40:52 volumio-x86-784 volumio[3930]: payload: {} Mar 28 20:40:52 volumio-x86-784 volumio[3930]: } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Stack trace: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: IncompleteAPIDataError: Missing data required to construct query string from bind params Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: error: [ytcr] Failed to start plugin: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: (AppError) Failed to start YouTubeApp Mar 28 20:40:52 volumio-x86-784 volumio[3930]: --->(SessionError) (YouTube Music) Failed to establish session Mar 28 20:40:52 volumio-x86-784 volumio[3930]: ------>(ConnectionError) (YouTube Music) Connection error in generating screen Id Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Error info: { Mar 28 20:40:52 volumio-x86-784 volumio[3930]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Mar 28 20:40:52 volumio-x86-784 volumio[3930]: } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: connect ETIMEDOUT 31.13.92.37:443 Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Stack trace: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: AppError: Failed to start YouTubeApp Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: LoungeScreenDisconnected { Mar 28 20:40:52 volumio-x86-784 volumio[3930]: AID: null, Mar 28 20:40:52 volumio-x86-784 volumio[3930]: name: 'loungeScreenDisconnected', Mar 28 20:40:52 volumio-x86-784 volumio[3930]: payload: {} Mar 28 20:40:52 volumio-x86-784 volumio[3930]: } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Mar 28 20:40:52 volumio-x86-784 volumio[3930]: Stack trace: Mar 28 20:40:52 volumio-x86-784 volumio[3930]: IncompleteAPIDataError: Missing data required to construct query string from bind params Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Mar 28 20:40:52 volumio-x86-784 volumio[3930]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::servicePause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ControllerMpd::pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:56 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: sendMpdCommand pause took 4 milliseconds Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:56 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:56 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 6 milliseconds Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 5 milliseconds Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 6 milliseconds Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":92148,"duration":228,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1770 Kbps","isStreaming":false,"title":"一万个不舍","artist":"杨不乖","album":"一万个不舍","uri":"INTERNAL/musics/单曲2026/2月/一万个不舍-杨不乖.flac","trackType":"flac"} Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":92148,"duration":228,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1770 Kbps","isStreaming":false,"title":"一万个不舍","artist":"杨不乖","album":"一万个不舍","uri":"INTERNAL/musics/单曲2026/2月/一万个不舍-杨不乖.flac","trackType":"flac"} Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ------------------------------ 70ms Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ------------------------------ 68ms Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 58 milliseconds Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":92148,"duration":228,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1770 Kbps","isStreaming":false,"title":"一万个不舍","artist":"杨不乖","album":"一万个不舍","uri":"INTERNAL/musics/单曲2026/2月/一万个不舍-杨不乖.flac","trackType":"flac"} Mar 28 20:40:56 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus pause Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:40:56 volumio-x86-784 volumio[3930]: info: ------------------------------ 100ms Mar 28 20:40:57 volumio-x86-784 sudo[2591]: pam_unix(sudo:session): session closed for user root Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPlay Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::play index 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::updateTrackBlock Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrackBlock Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::serviceStop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 1 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::serviceStop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::stop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand stop took 16 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::play index undefined Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::startPlaybackTimer Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 11 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand stop took 7 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 5 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 8 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand clear took 5 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac" Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一万个不舍","artist":"杨不乖","album":"一万个不舍","uri":"INTERNAL/musics/单曲2026/2月/一万个不舍-杨不乖.flac","trackType":"flac"} Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService stop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: No code Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 74ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 67ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: error: updateQueue error: null Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 60ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 56 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand add "INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac" took 56 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 76ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 41ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand play took 38 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 33ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 31ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 13 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 13 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 23ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 19 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 18ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 16 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 9 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 63ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 102ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Mar 28 20:40:58 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 102 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 103 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 101 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 27 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 247ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 243ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 236ms Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 148 milliseconds Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:40:58 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:40:58 volumio-x86-784 volumio[3930]: info: ------------------------------ 220ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPlay Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::play index 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::updateTrackBlock Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrackBlock Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::serviceStop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::serviceStop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::stop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand stop took 41 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::play index undefined Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::startPlaybackTimer Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 13 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand stop took 9 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 7 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 4 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand clear took 7 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac" Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService stop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: No code Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 20:41:02 volumio-x86-784 volumio[3930]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 77ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 69ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 66ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: error: updateQueue error: null Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 66ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand add "INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac" took 65 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 15ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 13ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 42ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand play took 45 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 37ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 34ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:02 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 47 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 46 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 43 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 34 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 24 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 17 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":115,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"792 Kbps","isStreaming":false,"title":"别在这个冬把我丢下","artist":"六小乐","album":"别在这个冬把我丢下（填词版）","uri":"INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac","trackType":"flac"} Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":115,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"792 Kbps","isStreaming":false,"title":"别在这个冬把我丢下","artist":"六小乐","album":"别在这个冬把我丢下（填词版）","uri":"INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac","trackType":"flac"} Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":115,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1252 Kbps","isStreaming":false,"title":"别在这个冬把我丢下","artist":"六小乐","album":"别在这个冬把我丢下（填词版）","uri":"INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac","trackType":"flac"} Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":115,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1252 Kbps","isStreaming":false,"title":"别在这个冬把我丢下","artist":"六小乐","album":"别在这个冬把我丢下（填词版）","uri":"INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac","trackType":"flac"} Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1486 Kbps","isStreaming":false,"title":"别在这个冬把我丢下","artist":"六小乐","album":"别在这个冬把我丢下（填词版）","uri":"INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac","trackType":"flac"} Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1486 Kbps","isStreaming":false,"title":"别在这个冬把我丢下","artist":"六小乐","album":"别在这个冬把我丢下（填词版）","uri":"INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac","trackType":"flac"} Mar 28 20:41:02 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 189ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 262ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 261ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 254ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 233ms Mar 28 20:41:02 volumio-x86-784 volumio[3930]: info: ------------------------------ 224ms Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioGetState Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:08 volumio-x86-784 sudo[4630]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Mar 28 20:41:08 volumio-x86-784 sudo[4630]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPlay Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::play index 21 Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stop Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::updateTrackBlock Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrackBlock Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::serviceStop Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 22 Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::serviceStop Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: ControllerMpd::stop Mar 28 20:41:08 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:08 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: sendMpdCommand stop took 122 milliseconds Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:08 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::play index undefined Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CoreStateMachine::startPlaybackTimer Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:08 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac Mar 28 20:41:08 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:08 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:08 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:08 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:08 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:08 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 13 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand stop took 8 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 6 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 4 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand clear took 10 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac" Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"别在这个冬把我丢下","artist":"六小乐","album":"别在这个冬把我丢下（填词版）","uri":"INTERNAL/musics/单曲2026/2月/别在这个冬把我丢下(填词版)-六小乐.flac","trackType":"flac"} Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService stop Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: No code Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 20:41:09 volumio-x86-784 volumio[3930]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 103ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 95ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 92ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: error: updateQueue error: null Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 93ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand add "INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac" took 89 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 14ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 10ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 19ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand play took 30 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 28ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 26ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 19 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 11 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 9 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:09 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 26 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 16 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 15 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":546,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"473 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 154ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 238ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 238ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 203 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 205 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 201 milliseconds Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:09 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 460ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 450ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: ------------------------------ 447ms Mar 28 20:41:09 volumio-x86-784 volumio[3930]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 28 20:41:09 volumio-x86-784 volumio[3930]: info: Retrying Login Due to Network Error Mar 28 20:41:12 volumio-x86-784 volumio[3930]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Mar 28 20:41:12 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioGetState Mar 28 20:41:12 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:13 volumio-x86-784 volumio[3930]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Mar 28 20:41:13 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioGetState Mar 28 20:41:13 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:19 volumio-x86-784 volumio[3930]: info: MyVolumio login type: Token Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::servicePause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ControllerMpd::pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:34 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: sendMpdCommand pause took 3 milliseconds Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:34 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:34 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 4 milliseconds Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 4 milliseconds Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 2 milliseconds Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26158,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"439 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26158,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"439 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26158,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"439 Kbps","isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:34 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus pause Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ------------------------------ 84ms Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ------------------------------ 85ms Mar 28 20:41:34 volumio-x86-784 volumio[3930]: info: ------------------------------ 84ms Mar 28 20:41:34 volumio-x86-784 sudo[4630]: pam_unix(sudo:session): session closed for user root Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPlay Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::play index 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::updateTrackBlock Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrackBlock Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::stPlaybackTimer Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::serviceStop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 21 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::serviceStop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::stop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand stop took 14 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::play index undefined Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::startPlaybackTimer Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/单曲2026/2月/嘉禾望岗-海来阿木.flac Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 10 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand stop took 5 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 4 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 3 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand clear took 6 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/单曲2026/2月/嘉禾望岗-海来阿木.flac" Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"别在冬天把我丢下","artist":"金多","album":"别在冬天把我丢下","uri":"INTERNAL/musics/单曲2026/2月/别在冬天把我丢下-金多.flac","trackType":"flac"} Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService stop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: No code Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 20:41:35 volumio-x86-784 volumio[3930]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 75ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 69ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 68ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: error: updateQueue error: null Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 67ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand add "INTERNAL/musics/单曲2026/2月/嘉禾望岗-海来阿木.flac" took 64 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 76ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 73ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 70ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand play took 78 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 76ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces system playlist update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Ignoring MPD Status Update Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 12 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 13 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 8 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 6ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Mar 28 20:41:35 volumio-x86-784 volumio[3930]: ---------------------------- MPD announces state update: player Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::getState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand status Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 8 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand status took 6 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":235,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1033 Kbps","isStreaming":false,"title":"嘉禾望岗","artist":"海来阿木","album":"嘉禾望岗","uri":"INTERNAL/musics/单曲2026/2月/嘉禾望岗-海来阿木.flac","trackType":"flac"} Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus stop Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":235,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1033 Kbps","isStreaming":false,"title":"嘉禾望岗","artist":"海来阿木","album":"嘉禾望岗","uri":"INTERNAL/musics/单曲2026/2月/嘉禾望岗-海来阿木.flac","trackType":"flac"} Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":235,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1033 Kbps","isStreaming":false,"title":"嘉禾望岗","artist":"海来阿木","album":"嘉禾望岗","uri":"INTERNAL/musics/单曲2026/2月/嘉禾望岗-海来阿木.flac","trackType":"flac"} Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 85ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 125ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 113ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: sendMpdCommand playlistinfo took 108 milliseconds Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: ControllerMpd::parseTrackInfo Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":235,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1033 Kbps","isStreaming":false,"title":"嘉禾望岗","artist":"海来阿木","album":"嘉禾望岗","uri":"INTERNAL/musics/单曲2026/2月/嘉禾望岗-海来阿木.flac","trackType":"flac"} Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ControllerMpd::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::servicePushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":235,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1033 Kbps","isStreaming":false,"title":"嘉禾望岗","artist":"海来阿木","album":"嘉禾望岗","uri":"INTERNAL/musics/单曲2026/2月/嘉禾望岗-海来阿木.flac","trackType":"flac"} Mar 28 20:41:35 volumio-x86-784 volumio[3930]: verbose: CURRENT POSITION 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState stateService play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::syncState currentStatus play Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: Received an update from plugin. extracting info from payload Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreStateMachine::pushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CorePlayQueue::getTrack 25 Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: CoreCommandRouter::volumioPushState Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 193ms Mar 28 20:41:35 volumio-x86-784 volumio[3930]: info: ------------------------------ 191ms Mar 28 20:41:37 volumio-x86-784 volumio[3930]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 20:41:37 volumio-x86-784 volumio[3930]: Error: connect ETIMEDOUT 31.13.80.54:443 Mar 28 20:41:37 volumio-x86-784 volumio[3930]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 28 20:41:37 volumio-x86-784 volumio[3930]: errno: -110, Mar 28 20:41:37 volumio-x86-784 volumio[3930]: code: 'ETIMEDOUT', Mar 28 20:41:37 volumio-x86-784 volumio[3930]: syscall: 'connect', Mar 28 20:41:37 volumio-x86-784 volumio[3930]: address: '31.13.80.54', Mar 28 20:41:37 volumio-x86-784 volumio[3930]: port: 443 Mar 28 20:41:37 volumio-x86-784 volumio[3930]: } Mar 28 20:41:37 volumio-x86-784 volumio[3930]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 20:41:37 volumio-x86-784 sudo[4724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-28 20:40 Mar 28 20:41:37 volumio-x86-784 sudo[4724]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"