-- Logs begin at Wed 2025-05-21 17:31:19 MSK, end at Wed 2025-05-21 21:37:28 MSK. -- May 21 21:36:01 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:36:01 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:01 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:36:01 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 21:36:11 volumio volumio[1305]: ALSA lib simple_none.c:1550:(simple_add1) helem (MIXER,'SoftMaster',0,7,0) appears twice or more May 21 21:36:11 volumio volumio[1305]: amixer: Mixer hw:0 load error: Invalid argument May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 21 21:36:11 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 21 21:36:13 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:36:13 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:36:13 volumio volumio[1305]: info: Discovery: Getting this device information May 21 21:36:13 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:36:13 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:36:25 volumio volumio[1305]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 21 21:36:25 volumio sudo[7224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 21:36:25 volumio sudo[7224]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:25 volumio sudo[7224]: pam_unix(sudo:session): session closed for user root May 21 21:36:25 volumio volumio[1305]: info: MPD Permissions set May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio sudo[7228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 21:36:25 volumio sudo[7228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:25 volumio systemd[1]: musicservicesshield.service: Succeeded. May 21 21:36:25 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 21 21:36:25 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 21 21:36:25 volumio systemd[1]: Stopping Music Player Daemon... May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 21 21:36:25 volumio systemd[1]: mpd.service: Succeeded. May 21 21:36:25 volumio systemd[1]: Stopped Music Player Daemon. May 21 21:36:25 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:25 volumio systemd[1]: Starting Music Player Daemon... May 21 21:36:25 volumio sudo[7235]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 21:36:25 volumio sudo[7235]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:25 volumio sudo[7235]: pam_unix(sudo:session): session closed for user root May 21 21:36:25 volumio mpd[7238]: May 21 21:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 21:36:25 volumio systemd[1]: Started Music Player Daemon. May 21 21:36:25 volumio sudo[7228]: pam_unix(sudo:session): session closed for user root May 21 21:36:25 volumio volumio[1305]: error: updateQueue error: null May 21 21:36:25 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 21 21:36:25 volumio volumio[7241]: cset: --> shielding system active with May 21 21:36:25 volumio volumio[7241]: cset: "system" cpuset of CPUSPEC(1-3) with 197 tasks running May 21 21:36:25 volumio volumio[7241]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 21 21:36:25 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 21 21:36:27 volumio volumio[1305]: info: CoreCommandRouter::volumioNext May 21 21:36:27 volumio volumio[1305]: info: CoreStateMachine::next May 21 21:36:27 volumio volumio[1305]: info: ControllerMpd::next May 21 21:36:27 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand next May 21 21:36:29 volumio volumio[1305]: info: CoreCommandRouter::volumioPause May 21 21:36:29 volumio volumio[1305]: info: CoreStateMachine::pause May 21 21:36:29 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer May 21 21:36:29 volumio volumio[1305]: info: CoreStateMachine::servicePause May 21 21:36:29 volumio volumio[1305]: info: CorePlayQueue::getTrack 5 May 21 21:36:29 volumio volumio[1305]: info: CoreCommandRouter::servicePause May 21 21:36:29 volumio volumio[1305]: info: [1747852589435] ControllerUPNPBrowser::pause May 21 21:36:29 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:36:29 volumio volumio[1305]: info: sendMpdCommand pause took 1 milliseconds May 21 21:36:30 volumio volumio[1305]: info: CoreCommandRouter::volumioNext May 21 21:36:30 volumio volumio[1305]: info: CoreStateMachine::next May 21 21:36:30 volumio volumio[1305]: info: ControllerMpd::next May 21 21:36:30 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand next May 21 21:36:31 volumio volumio[1305]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: May 21 21:36:31 volumio volumio[1305]: LoungeScreenDisconnected { May 21 21:36:31 volumio volumio[1305]: AID: null, May 21 21:36:31 volumio volumio[1305]: name: 'loungeScreenDisconnected', May 21 21:36:31 volumio volumio[1305]: payload: {} May 21 21:36:31 volumio volumio[1305]: } May 21 21:36:31 volumio volumio[1305]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 21 21:36:31 volumio volumio[1305]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 21 21:36:31 volumio volumio[1305]: Stack trace: May 21 21:36:31 volumio volumio[1305]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 21 21:36:31 volumio volumio[1305]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 21 21:36:31 volumio volumio[1305]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 21 21:36:31 volumio volumio[1305]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 21 21:36:31 volumio volumio[1305]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 21 21:36:31 volumio volumio[1305]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) May 21 21:36:31 volumio volumio[1305]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 May 21 21:36:31 volumio volumio[1305]: at new Promise () May 21 21:36:31 volumio volumio[1305]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) May 21 21:36:31 volumio volumio[1305]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) May 21 21:36:31 volumio volumio[1305]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) May 21 21:36:31 volumio volumio[1305]: at runMicrotasks () May 21 21:36:31 volumio volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 21 21:36:31 volumio volumio[1305]: at async Promise.all (index 0) May 21 21:36:31 volumio volumio[1305]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) May 21 21:36:31 volumio volumio[1305]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) May 21 21:36:31 volumio volumio[1305]: error: [yt-cast-receiver] Failed to start receiver: May 21 21:36:31 volumio volumio[1305]: (AppError) Failed to start YouTubeApp May 21 21:36:31 volumio volumio[1305]: --->(SessionError) (YouTube) Failed to establish session May 21 21:36:31 volumio volumio[1305]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id May 21 21:36:31 volumio volumio[1305]: Error info: { May 21 21:36:31 volumio volumio[1305]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' May 21 21:36:31 volumio volumio[1305]: } May 21 21:36:31 volumio volumio[1305]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: read ETIMEDOUT May 21 21:36:31 volumio volumio[1305]: Stack trace: May 21 21:36:31 volumio volumio[1305]: AppError: Failed to start YouTubeApp May 21 21:36:31 volumio volumio[1305]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) May 21 21:36:31 volumio volumio[1305]: at runMicrotasks () May 21 21:36:31 volumio volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 21 21:36:31 volumio volumio[1305]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) May 21 21:36:31 volumio volumio[1305]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: May 21 21:36:31 volumio volumio[1305]: LoungeScreenDisconnected { May 21 21:36:31 volumio volumio[1305]: AID: null, May 21 21:36:31 volumio volumio[1305]: name: 'loungeScreenDisconnected', May 21 21:36:31 volumio volumio[1305]: payload: {} May 21 21:36:31 volumio volumio[1305]: } May 21 21:36:31 volumio volumio[1305]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 21 21:36:31 volumio volumio[1305]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 21 21:36:31 volumio volumio[1305]: Stack trace: May 21 21:36:31 volumio volumio[1305]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 21 21:36:31 volumio volumio[1305]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 21 21:36:31 volumio volumio[1305]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 21 21:36:31 volumio volumio[1305]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 21 21:36:31 volumio volumio[1305]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 21 21:36:31 volumio volumio[1305]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) May 21 21:36:31 volumio volumio[1305]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 May 21 21:36:31 volumio volumio[1305]: at new Promise () May 21 21:36:31 volumio volumio[1305]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) May 21 21:36:31 volumio volumio[1305]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) May 21 21:36:31 volumio volumio[1305]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 May 21 21:36:31 volumio volumio[1305]: at Array.forEach () May 21 21:36:31 volumio volumio[1305]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) May 21 21:36:31 volumio volumio[1305]: at runMicrotasks () May 21 21:36:31 volumio volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 21 21:36:31 volumio volumio[1305]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) May 21 21:36:31 volumio volumio[1305]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: May 21 21:36:31 volumio volumio[1305]: LoungeScreenDisconnected { May 21 21:36:31 volumio volumio[1305]: AID: null, May 21 21:36:31 volumio volumio[1305]: name: 'loungeScreenDisconnected', May 21 21:36:31 volumio volumio[1305]: payload: {} May 21 21:36:31 volumio volumio[1305]: } May 21 21:36:31 volumio volumio[1305]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 21 21:36:31 volumio volumio[1305]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 21 21:36:31 volumio volumio[1305]: Stack trace: May 21 21:36:31 volumio volumio[1305]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 21 21:36:31 volumio volumio[1305]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 21 21:36:31 volumio volumio[1305]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 21 21:36:31 volumio volumio[1305]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 21 21:36:31 volumio volumio[1305]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 21 21:36:31 volumio volumio[1305]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 21 21:36:31 volumio volumio[1305]: at runMicrotasks () May 21 21:36:31 volumio volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 21 21:36:31 volumio volumio[1305]: error: [ytcr] Failed to start plugin: May 21 21:36:31 volumio volumio[1305]: (AppError) Failed to start YouTubeApp May 21 21:36:31 volumio volumio[1305]: --->(SessionError) (YouTube) Failed to establish session May 21 21:36:31 volumio volumio[1305]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id May 21 21:36:31 volumio volumio[1305]: Error info: { May 21 21:36:31 volumio volumio[1305]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' May 21 21:36:31 volumio volumio[1305]: } May 21 21:36:31 volumio volumio[1305]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: read ETIMEDOUT May 21 21:36:31 volumio volumio[1305]: Stack trace: May 21 21:36:31 volumio volumio[1305]: AppError: Failed to start YouTubeApp May 21 21:36:31 volumio volumio[1305]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) May 21 21:36:31 volumio volumio[1305]: at runMicrotasks () May 21 21:36:31 volumio volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 21 21:36:31 volumio volumio[1305]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) May 21 21:36:31 volumio volumio[1305]: info: Done. May 21 21:36:33 volumio volumio[1305]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: May 21 21:36:33 volumio volumio[1305]: LoungeScreenDisconnected { May 21 21:36:33 volumio volumio[1305]: AID: null, May 21 21:36:33 volumio volumio[1305]: name: 'loungeScreenDisconnected', May 21 21:36:33 volumio volumio[1305]: payload: {} May 21 21:36:33 volumio volumio[1305]: } May 21 21:36:33 volumio volumio[1305]: (IncompleteAPIDataError) Missing data required to construct query string from bind params May 21 21:36:33 volumio volumio[1305]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } May 21 21:36:33 volumio volumio[1305]: Stack trace: May 21 21:36:33 volumio volumio[1305]: IncompleteAPIDataError: Missing data required to construct query string from bind params May 21 21:36:33 volumio volumio[1305]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) May 21 21:36:33 volumio volumio[1305]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) May 21 21:36:33 volumio volumio[1305]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) May 21 21:36:33 volumio volumio[1305]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) May 21 21:36:33 volumio volumio[1305]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) May 21 21:36:33 volumio volumio[1305]: at runMicrotasks () May 21 21:36:33 volumio volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:95:5) May 21 21:36:33 volumio volumio[1305]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 May 21 21:36:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:36:34 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:34 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:36:34 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:36 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:36:36 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:37 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:36:38 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:39 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:36:39 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:36:40 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:40 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::ClearQueue May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::stop May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::pushState May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrack 5 May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::serviceStop May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrack 5 May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::serviceStop May 21 21:36:40 volumio volumio[1305]: info: [1747852600795] ControllerUPNPBrowser::stop May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::clearPlayQueue May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::saveQueue May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::addQueueItems May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::addQueueItems May 21 21:36:40 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/folder/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$0 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/folder/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$0 May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$7 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$7 May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::saveQueue May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::volumioPlay May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::play index 1 May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::addQueueItems May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::addQueueItems May 21 21:36:40 volumio volumio[1305]: info: Preload queue cleared May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$6 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$6 May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$9 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$9 May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$1 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$1 May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$8 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$8 May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$3 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$3 May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$4 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$4 May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$2 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$2 May 21 21:36:40 volumio volumio[1305]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$5 May 21 21:36:40 volumio volumio[1305]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$6$1$2$5 May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::stop May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::volumioPushQueue May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::saveQueue May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::play index undefined May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::updateTrackBlock May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrackBlock May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrack 1 May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::startPlaybackTimer May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrack 1 May 21 21:36:40 volumio volumio[1305]: info: [1747852600799] ControllerUPNPBrowser::clearAddPlayTrack May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand stop May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand stop took 6 milliseconds May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand stop took 2 milliseconds May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand clear May 21 21:36:40 volumio volumio[1305]: info: May 21 21:36:40 volumio volumio[1305]: ---------------------------- MPD announces system playlist update May 21 21:36:40 volumio volumio[1305]: info: Ignoring MPD Status Update May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand clear took 0 milliseconds May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.1:8200/MediaItems/1607.dsf" May 21 21:36:40 volumio volumio[1305]: error: updateQueue error: null May 21 21:36:40 volumio volumio[1305]: info: ------------------------------ 1ms May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:8200/MediaItems/1607.dsf" May 21 21:36:40 volumio volumio[1305]: info: May 21 21:36:40 volumio volumio[1305]: ---------------------------- MPD announces system playlist update May 21 21:36:40 volumio volumio[1305]: info: Ignoring MPD Status Update May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand add "http://192.168.1.1:8200/MediaItems/1607.dsf" took 1 milliseconds May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand play May 21 21:36:40 volumio volumio[1305]: info: ------------------------------ 1ms May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand play took 0 milliseconds May 21 21:36:40 volumio volumio[1305]: info: May 21 21:36:40 volumio volumio[1305]: ---------------------------- MPD announces state update: player May 21 21:36:40 volumio volumio[1305]: info: ControllerMpd::getState May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status May 21 21:36:40 volumio volumio[1305]: info: May 21 21:36:40 volumio volumio[1305]: ---------------------------- MPD announces state update: player May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand status took 20 milliseconds May 21 21:36:40 volumio volumio[1305]: info: ControllerMpd::getState May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand status May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::parseState May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand status took 0 milliseconds May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::parseState May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo May 21 21:36:40 volumio volumio[1305]: info: ControllerMpd::pushState May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::servicePushState May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrack 1 May 21 21:36:40 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1607.dsf","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/1607.dsf","trackType":"dsf"} May 21 21:36:40 volumio volumio[1305]: verbose: CURRENT POSITION 1 May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus stop May 21 21:36:40 volumio volumio[1305]: info: ------------------------------ 21ms May 21 21:36:40 volumio volumio[1305]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:36:40 volumio volumio[1305]: verbose: ControllerMpd::parseTrackInfo May 21 21:36:40 volumio volumio[1305]: info: ControllerMpd::pushState May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::servicePushState May 21 21:36:40 volumio volumio[1305]: info: CorePlayQueue::getTrack 1 May 21 21:36:40 volumio volumio[1305]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":309,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1607.dsf","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/1607.dsf","trackType":"dsf"} May 21 21:36:40 volumio volumio[1305]: verbose: CURRENT POSITION 1 May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::syncState stateService play May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::syncState currentStatus play May 21 21:36:40 volumio volumio[1305]: info: Received an update from plugin. extracting info from payload May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::pushState May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState May 21 21:36:40 volumio volumio[1305]: info: CoreStateMachine::pushState May 21 21:36:40 volumio volumio[1305]: info: CoreCommandRouter::volumioPushState May 21 21:36:40 volumio volumio[1305]: info: ------------------------------ 3ms May 21 21:36:40 volumio volumio[1305]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! 1607.dsf May 21 21:36:40 volumio volumio[1305]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! 1607.dsf May 21 21:36:43 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:36:43 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:36:43 volumio volumio[1305]: info: Discovery: Getting this device information May 21 21:36:43 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:36:43 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:36:50 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 21:36:50 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 21 21:36:58 volumio volumio[1305]: info: Enabling plugin fusiondsp May 21 21:36:58 volumio volumio[1305]: info: Loading plugin "fusiondsp"... May 21 21:36:58 volumio volumio[1305]: info: Applying required configuration parameters for plugin fusiondsp May 21 21:36:58 volumio volumio[1305]: info: Preparing to generate the ALSA configuration file May 21 21:36:58 volumio volumio[1305]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 21 21:36:58 volumio volumio[1305]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 21 21:36:58 volumio volumio[1305]: info: Reading ALSA contributions from plugins. May 21 21:36:58 volumio volumio[1305]: info: Asound.conf file written May 21 21:36:58 volumio sudo[7367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 21 21:36:58 volumio sudo[7367]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio sudo[7367]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio volumio[1305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '3:3:0:Playback Channel Map:0' : Device or resource busy May 21 21:36:58 volumio volumio[1305]: info: Output device has changed, restarting MPD May 21 21:36:58 volumio sudo[7373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 21:36:58 volumio sudo[7373]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio sudo[7373]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio volumio[1305]: info: Output device has changed, restarting Shairport Sync May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:36:58 volumio sudo[7376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 21:36:58 volumio sudo[7376]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio systemd[1]: musicservicesshield.service: Succeeded. May 21 21:36:58 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 21 21:36:58 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 21 21:36:58 volumio systemd[1]: Stopping Music Player Daemon... May 21 21:36:58 volumio systemd[1]: mpd.service: Succeeded. May 21 21:36:58 volumio systemd[1]: Stopped Music Player Daemon. May 21 21:36:58 volumio volumio[1305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 21:36:58 volumio volumio[1305]: info: PLUGIN START: fusiondsp May 21 21:36:58 volumio volumio[1305]: info: Loading i18n strings for locale ru May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: FusionDsp - mixtype--------------------- Software May 21 21:36:58 volumio volumio[1305]: info: Preparing to generate the ALSA configuration file May 21 21:36:58 volumio volumio[1305]: info: Done. May 21 21:36:58 volumio volumio[1305]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 21 21:36:58 volumio volumio[1305]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 21 21:36:58 volumio volumio[1305]: info: Reading ALSA contributions from plugins. May 21 21:36:58 volumio volumio[1305]: info: MPD Permissions set May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio systemd[1]: Starting Music Player Daemon... May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: Starting Shairport Sync May 21 21:36:58 volumio sudo[7381]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 21:36:58 volumio sudo[7381]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio sudo[7381]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::volumioPause May 21 21:36:58 volumio volumio[1305]: info: CoreStateMachine::pause May 21 21:36:58 volumio volumio[1305]: info: CoreStateMachine::stPlaybackTimer May 21 21:36:58 volumio volumio[1305]: info: CoreStateMachine::servicePause May 21 21:36:58 volumio volumio[1305]: info: CorePlayQueue::getTrack 1 May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::servicePause May 21 21:36:58 volumio volumio[1305]: info: [1747852618239] ControllerUPNPBrowser::pause May 21 21:36:58 volumio volumio[1305]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:36:58 volumio volumio[1305]: info: mpdhttpout --- Volumio set on pause May 21 21:36:58 volumio volumio[1305]: info: mpdhttpout ---Configuration successfully added to mpd.conf. May 21 21:36:58 volumio volumio[1305]: error: MPD error: Error: This socket has been ended by the other party May 21 21:36:58 volumio volumio[1305]: error: This socket has been ended by the other party {"code":"EPIPE"} May 21 21:36:58 volumio volumio[1305]: error: MPD error: Error: This socket has been ended by the other party May 21 21:36:58 volumio volumio[1305]: error: This socket has been ended by the other party {"code":"EPIPE"} May 21 21:36:58 volumio volumio[1305]: error: MPD error: Error: This socket has been ended by the other party May 21 21:36:58 volumio volumio[1305]: error: This socket has been ended by the other party {"code":"EPIPE"} May 21 21:36:58 volumio volumio[1305]: info: Asound.conf file unchanged, so no further update is needed May 21 21:36:58 volumio volumio[1305]: info: Output device has changed, restarting MPD May 21 21:36:58 volumio sudo[7391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 21:36:58 volumio sudo[7391]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 21:36:58 volumio systemd[1]: shairport-sync.service: Succeeded. May 21 21:36:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 21:36:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 21:36:58 volumio sudo[7391]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio sudo[7394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 21:36:58 volumio sudo[7394]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio volumio[1305]: info: Output device has changed, restarting Shairport Sync May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:36:58 volumio sudo[7398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 21:36:58 volumio sudo[7398]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio sudo[7394]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 21:36:58 volumio systemd[1]: mpd.service: Succeeded. May 21 21:36:58 volumio systemd[1]: Stopped Music Player Daemon. May 21 21:36:58 volumio systemd[1]: Starting Music Player Daemon... May 21 21:36:58 volumio volumio[1305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 21:36:58 volumio volumio[1305]: info: MPD Permissions set May 21 21:36:58 volumio volumio[1305]: info: Shairport-Sync Started May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 21 21:36:58 volumio sudo[7405]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 21:36:58 volumio sudo[7405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio sudo[7405]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:36:58 volumio volumio[1305]: info: Starting Shairport Sync May 21 21:36:58 volumio volumio[1305]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:36:58 volumio volumio[1305]: info: CoreCommandRouter::volumioPause May 21 21:36:58 volumio volumio[1305]: info: CoreStateMachine::pause May 21 21:36:58 volumio volumio[1305]: info: mpdhttpout --- Volumio set on pause May 21 21:36:58 volumio sudo[7415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 21:36:58 volumio sudo[7415]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:36:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 21:36:58 volumio systemd[1]: shairport-sync.service: Succeeded. May 21 21:36:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 21:36:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 21:36:58 volumio sudo[7415]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio volumio[1305]: info: Shairport-Sync Started May 21 21:36:58 volumio mpd[7412]: May 21 21:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 21:36:58 volumio systemd[1]: Started Music Player Daemon. May 21 21:36:58 volumio sudo[7376]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio sudo[7398]: pam_unix(sudo:session): session closed for user root May 21 21:36:58 volumio volumio[1305]: error: updateQueue error: null May 21 21:36:58 volumio volumio[1305]: error: updateQueue error: null May 21 21:36:58 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 21 21:36:58 volumio volumio[7422]: cset: --> shielding system active with May 21 21:36:58 volumio volumio[7422]: cset: "system" cpuset of CPUSPEC(1-3) with 197 tasks running May 21 21:36:58 volumio volumio[7422]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 21 21:36:58 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 21 21:37:00 volumio volumio[1305]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 21 21:37:00 volumio volumio[1305]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 21 21:37:00 volumio volumio[1305]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 21 21:37:00 volumio volumio[1305]: info: camilladsp service started and running in background, instance 2 May 21 21:37:00 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 21:37:00 volumio volumio[1305]: cannot open device 'volumioHw': Device or resource busy May 21 21:37:00 volumio volumio[1305]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 21 21:37:00 volumio volumio[1305]: cannot open device 'volumioHw': Device or resource busy May 21 21:37:00 volumio volumio[1305]: info: FusionDsp loaded May 21 21:37:00 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:37:00 volumio sudo[7453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 21 21:37:00 volumio sudo[7453]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - Reporting Fusion DSP Enabled May 21 21:37:00 volumio volumio[1305]: info: Adding Signal Path Element [object Object] May 21 21:37:00 volumio volumio[1305]: info: Adding fusiondspeq DSP Signal Path Element May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - ---- installed callbackRead May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - ---- read samplerate from file: 384000 May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - ---- read samplerate from file: 384000 May 21 21:37:00 volumio volumio[1305]: info: camilladsp stopping service pid 7443... May 21 21:37:00 volumio volumio[1305]: info: camilladsp service terminated, instance 2 May 21 21:37:00 volumio volumio[1305]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 21 21:37:00 volumio volumio[1305]: info: camilladsp service started and running in background, instance 2 May 21 21:37:00 volumio volumio[1305]: error: FusionDsp - WebSocket error: [object Object] May 21 21:37:00 volumio volumio[1305]: error: FusionDsp - WebSocket error: [object Object] May 21 21:37:00 volumio volumio[1305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 21 21:37:00 volumio systemd[1]: Started FusionDsp Daemon. May 21 21:37:00 volumio sudo[7453]: pam_unix(sudo:session): session closed for user root May 21 21:37:00 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 21 21:37:00 volumio sudo[7466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 21:37:00 volumio sudo[7466]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:00 volumio systemd[1]: musicservicesshield.service: Succeeded. May 21 21:37:00 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 21 21:37:00 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 21 21:37:00 volumio systemd[1]: Stopping Music Player Daemon... May 21 21:37:00 volumio systemd[1]: mpd.service: Succeeded. May 21 21:37:00 volumio systemd[1]: Stopped Music Player Daemon. May 21 21:37:00 volumio systemd[1]: Starting Music Player Daemon... May 21 21:37:00 volumio sudo[7468]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 21:37:00 volumio sudo[7468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:00 volumio sudo[7468]: pam_unix(sudo:session): session closed for user root May 21 21:37:00 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 21 21:37:00 volumio sudo[7472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 21:37:00 volumio sudo[7472]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:00 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 21:37:00 volumio systemd[1]: mpd.service: Succeeded. May 21 21:37:00 volumio systemd[1]: Stopped Music Player Daemon. May 21 21:37:00 volumio systemd[1]: Starting Music Player Daemon... May 21 21:37:00 volumio sudo[7474]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 21:37:00 volumio sudo[7474]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:00 volumio sudo[7474]: pam_unix(sudo:session): session closed for user root May 21 21:37:00 volumio mpd[7476]: May 21 21:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 21:37:00 volumio systemd[1]: Started Music Player Daemon. May 21 21:37:00 volumio sudo[7466]: pam_unix(sudo:session): session closed for user root May 21 21:37:00 volumio volumio[1305]: error: updateQueue error: null May 21 21:37:00 volumio sudo[7472]: pam_unix(sudo:session): session closed for user root May 21 21:37:00 volumio volumio[1305]: error: updateQueue error: null May 21 21:37:00 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 21 21:37:00 volumio volumio[7479]: cset: --> shielding system active with May 21 21:37:00 volumio volumio[7479]: cset: "system" cpuset of CPUSPEC(1-3) with 205 tasks running May 21 21:37:00 volumio volumio[7479]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 21 21:37:00 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 21 21:37:01 volumio volumio[1305]: error: Cannot download Available plugins list: Error: ETIMEDOUT May 21 21:37:01 volumio volumio[1305]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 21:37:01 volumio volumio[1305]: info: FusionDsp - May 21 21:37:01 volumio volumio[1305]: info: FusionDsp - undefined May 21 21:37:05 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:37:05 volumio sudo[7496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 21:37:05 volumio sudo[7496]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:05 volumio sudo[7496]: pam_unix(sudo:session): session closed for user root May 21 21:37:05 volumio sudo[7499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 21:37:05 volumio sudo[7499]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:05 volumio sudo[7499]: pam_unix(sudo:session): session closed for user root May 21 21:37:05 volumio volumio[1305]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.79 UA: Mozilla/5.0 (Linux; Android 15; ELI-NX9 Build/HONORELI-N39; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 21 21:37:05 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 21:37:06 volumio sudo[7516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 21:37:06 volumio sudo[7516]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:06 volumio sudo[7516]: pam_unix(sudo:session): session closed for user root May 21 21:37:06 volumio sudo[7519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 21:37:06 volumio sudo[7519]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:37:06 volumio sudo[7519]: pam_unix(sudo:session): session closed for user root May 21 21:37:06 volumio volumio[1305]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.79 UA: Mozilla/5.0 (Linux; Android 15; ELI-NX9 Build/HONORELI-N39; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::volumioGetVisibleSources May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 21:37:06 volumio volumio[1305]: info: Received Get System Info May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:37:06 volumio volumio[1305]: info: Discovery: Getting this device information May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:37:06 volumio volumio[1305]: info: Listing playlists May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 21:37:06 volumio volumio[1305]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 21:37:06 volumio volumio[1305]: info: FusionDsp - May 21 21:37:06 volumio volumio[1305]: info: FusionDsp - undefined May 21 21:37:07 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 21 21:37:07 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 21:37:07 volumio volumio[1305]: info: Received Get System Info May 21 21:37:07 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:37:07 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:37:07 volumio volumio[1305]: info: Discovery: Getting this device information May 21 21:37:07 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:37:07 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:37:09 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 21:37:09 volumio volumio[1305]: info: Received Get System Info May 21 21:37:09 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:37:09 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:37:09 volumio volumio[1305]: info: Discovery: Getting this device information May 21 21:37:09 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:37:09 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:37:10 volumio volumio[1305]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 21:37:10 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 21 21:37:10 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 21 21:37:10 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 21 21:37:10 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 21 21:37:10 volumio volumio[1305]: info: Received Get System Version May 21 21:37:10 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 21:37:13 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:37:13 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:37:13 volumio volumio[1305]: info: Discovery: Getting this device information May 21 21:37:13 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:37:13 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:37:15 volumio volumio[1305]: info: CoreCommandRouter::volumioGetQueue May 21 21:37:15 volumio volumio[1305]: info: CoreStateMachine::getQueue May 21 21:37:15 volumio volumio[1305]: info: CorePlayQueue::getQueue May 21 21:37:16 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 21 21:37:18 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:37:18 volumio volumio[1305]: info: Preload queue cleared May 21 21:37:19 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:37:19 volumio volumio[1305]: info: Preload queue cleared May 21 21:37:21 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:37:21 volumio volumio[1305]: info: Preload queue cleared May 21 21:37:22 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:37:22 volumio volumio[1305]: info: Preload queue cleared May 21 21:37:24 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:37:24 volumio volumio[1305]: info: Preload queue cleared May 21 21:37:24 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 21:37:24 volumio volumio[1305]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 21 21:37:24 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 21 21:37:24 volumio volumio[1305]: info: Received Get System Version May 21 21:37:24 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 21:37:24 volumio volumio[1305]: info: Received Get System Info May 21 21:37:24 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:37:24 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:37:24 volumio volumio[1305]: info: Discovery: Getting this device information May 21 21:37:24 volumio volumio[1305]: info: CoreCommandRouter::volumioGetState May 21 21:37:24 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:37:27 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:37:27 volumio volumio[1305]: info: Preload queue cleared May 21 21:37:28 volumio volumio[1305]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 21:37:28 volumio volumio[1305]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 21:37:28 volumio volumio[1305]: TypeError: Cannot read property 'length' of undefined May 21 21:37:28 volumio volumio[1305]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 21 21:37:28 volumio volumio[1305]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 21 21:37:28 volumio volumio[1305]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 21 21:37:28 volumio volumio[1305]: at Parser.emit (events.js:400:28) May 21 21:37:28 volumio volumio[1305]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 21 21:37:28 volumio volumio[1305]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 21 21:37:28 volumio volumio[1305]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 21 21:37:28 volumio volumio[1305]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 21 21:37:28 volumio volumio[1305]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 21 21:37:28 volumio volumio[1305]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 21 21:37:28 volumio volumio[1305]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 21 21:37:28 volumio volumio[1305]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 21 21:37:28 volumio volumio[1305]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 21 21:37:28 volumio volumio[1305]: at IncomingMessage.emit (events.js:412:35) May 21 21:37:28 volumio volumio[1305]: at endReadableNT (internal/streams/readable.js:1333:12) May 21 21:37:28 volumio volumio[1305]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 21 21:37:28 volumio volumio[1305]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 21:37:28 volumio sudo[7569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 21:36 May 21 21:37:28 volumio sudo[7569]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"