-- Logs begin at Thu 2025-07-03 16:01:07 CST, end at Thu 2025-07-03 16:21:44 CST. -- Jul 03 16:20:03 volumio volumio[984]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 12 Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:03 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 16:20:03 volumio volumio[984]: info: Received Get System Info Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:20:03 volumio volumio[984]: info: Discovery: Getting this device information Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:03 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:03 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:03 volumio volumio[984]: info: Listing playlists Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 16:20:03 volumio volumio[984]: info: Received Get System Info Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:20:03 volumio volumio[984]: info: Discovery: Getting this device information Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:03 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:03 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 16:20:03 volumio volumio[984]: info: Received Get System Info Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:20:03 volumio volumio[984]: info: Discovery: Getting this device information Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:03 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:03 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:03 volumio volumio[984]: info: Listing playlists Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 03 16:20:03 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 03 16:20:05 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 03 16:20:05 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 03 16:20:10 volumio volumio[984]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 03 16:20:10 volumio volumio[984]: info: Retrying Login Due to Network Error Jul 03 16:20:11 volumio kernel: usb 1-4: device descriptor read/64, error -110 Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 03 16:20:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 16:20:18 volumio volumio[984]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 03 16:20:18 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 03 16:20:18 volumio volumio[984]: info: Preparing to save Alsa Options, stopping services first Jul 03 16:20:18 volumio volumio[984]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:18 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:18 volumio volumio[984]: info: CoreCommandRouter::volumioPause Jul 03 16:20:18 volumio volumio[984]: info: CoreStateMachine::pause Jul 03 16:20:18 volumio volumio[984]: info: CoreStateMachine::stPlaybackTimer Jul 03 16:20:18 volumio volumio[984]: info: CoreStateMachine::servicePause Jul 03 16:20:18 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:18 volumio volumio[984]: info: CoreCommandRouter::servicePause Jul 03 16:20:18 volumio volumio[984]: info: ControllerMpd::pause Jul 03 16:20:18 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand pause Jul 03 16:20:18 volumio volumio[984]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB AUDIO DAC"}} Jul 03 16:20:18 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:18 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:18 volumio volumio[984]: info: Setting mixer PCM for card USB AUDIO DAC Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 03 16:20:19 volumio volumio[984]: info: Updating Volume Controller Parameters: Device: 5 Name: USB AUDIO DAC Mixer: PCM Max Vol: 100 Vol Curve; linear Vol Steps: 2 Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 03 16:20:19 volumio volumio[984]: info: Disabling external Volume Control Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 16:20:19 volumio volumio[984]: info: Preparing to generate the ALSA configuration file Jul 03 16:20:19 volumio volumio[984]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 03 16:20:19 volumio volumio[984]: info: Reading ALSA contributions from plugins. Jul 03 16:20:19 volumio volumio[984]: info: Jul 03 16:20:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand pause took 424 milliseconds Jul 03 16:20:19 volumio volumio[984]: info: ControllerMpd::getState Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Jul 03 16:20:19 volumio volumio[984]: info: Jul 03 16:20:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Jul 03 16:20:19 volumio volumio[984]: info: ControllerMpd::getState Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Jul 03 16:20:19 volumio volumio[984]: info: Jul 03 16:20:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Jul 03 16:20:19 volumio volumio[984]: info: ControllerMpd::getState Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Jul 03 16:20:19 volumio volumio[984]: info: Jul 03 16:20:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Jul 03 16:20:19 volumio volumio[984]: info: ControllerMpd::getState Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand status took 14 milliseconds Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand status took 12 milliseconds Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand status took 11 milliseconds Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand status took 9 milliseconds Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::parseState Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::parseState Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::parseState Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::parseState Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand playlistinfo took 53 milliseconds Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand playlistinfo took 52 milliseconds Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand playlistinfo took 51 milliseconds Jul 03 16:20:19 volumio volumio[984]: info: sendMpdCommand playlistinfo took 56 milliseconds Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Jul 03 16:20:19 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Jul 03 16:20:19 volumio volumio[984]: info: ControllerMpd::pushState Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::servicePushState Jul 03 16:20:19 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:19 volumio volumio[984]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":73990,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 ?????","artist":"????","album":"????CD1","uri":"USB/____/发烧国语女声/丽江小倩 - 丽江小倩迎风飞扬CD1/01.01 爱你的方式.wav","trackType":"wav"} Jul 03 16:20:19 volumio volumio[984]: verbose: CURRENT POSITION 0 Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::syncState stateService pause Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus pause Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::pushState Jul 03 16:20:19 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::stPlaybackTimer Jul 03 16:20:19 volumio volumio[984]: info: ControllerMpd::pushState Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::servicePushState Jul 03 16:20:19 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:19 volumio volumio[984]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":73990,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 ?????","artist":"????","album":"????CD1","uri":"USB/____/发烧国语女声/丽江小倩 - 丽江小倩迎风飞扬CD1/01.01 爱你的方式.wav","trackType":"wav"} Jul 03 16:20:19 volumio volumio[984]: verbose: CURRENT POSITION 0 Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::syncState stateService pause Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus pause Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::pushState Jul 03 16:20:19 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::stPlaybackTimer Jul 03 16:20:19 volumio volumio[984]: info: ControllerMpd::pushState Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::servicePushState Jul 03 16:20:19 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:19 volumio volumio[984]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":73990,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 ?????","artist":"????","album":"????CD1","uri":"USB/____/发烧国语女声/丽江小倩 - 丽江小倩迎风飞扬CD1/01.01 爱你的方式.wav","trackType":"wav"} Jul 03 16:20:19 volumio volumio[984]: verbose: CURRENT POSITION 0 Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::syncState stateService pause Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus pause Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::pushState Jul 03 16:20:19 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::stPlaybackTimer Jul 03 16:20:19 volumio volumio[984]: info: ControllerMpd::pushState Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::servicePushState Jul 03 16:20:19 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:19 volumio volumio[984]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":73990,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 ?????","artist":"????","album":"????CD1","uri":"USB/____/发烧国语女声/丽江小倩 - 丽江小倩迎风飞扬CD1/01.01 爱你的方式.wav","trackType":"wav"} Jul 03 16:20:19 volumio volumio[984]: verbose: CURRENT POSITION 0 Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::syncState stateService pause Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus pause Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::pushState Jul 03 16:20:19 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:19 volumio volumio[984]: info: CoreStateMachine::stPlaybackTimer Jul 03 16:20:19 volumio volumio[984]: info: ------------------------------ 263ms Jul 03 16:20:19 volumio volumio[984]: info: ------------------------------ 261ms Jul 03 16:20:19 volumio volumio[984]: info: ------------------------------ 262ms Jul 03 16:20:19 volumio volumio[984]: info: ------------------------------ 261ms Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 03 16:20:19 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 16:20:19 volumio volumio[984]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 16:20:19 volumio volumio[984]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jul 03 16:20:19 volumio volumio[984]: at Object.readdirSync (fs.js:1048:3) Jul 03 16:20:19 volumio volumio[984]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jul 03 16:20:19 volumio volumio[984]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jul 03 16:20:19 volumio volumio[984]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jul 03 16:20:19 volumio volumio[984]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jul 03 16:20:19 volumio volumio[984]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jul 03 16:20:19 volumio volumio[984]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 03 16:20:19 volumio volumio[984]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jul 03 16:20:19 volumio volumio[984]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jul 03 16:20:19 volumio volumio[984]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) { Jul 03 16:20:19 volumio volumio[984]: errno: -20, Jul 03 16:20:19 volumio volumio[984]: syscall: 'scandir', Jul 03 16:20:19 volumio volumio[984]: code: 'ENOTDIR', Jul 03 16:20:19 volumio volumio[984]: path: '/tmp/peppyrunning' Jul 03 16:20:19 volumio volumio[984]: } Jul 03 16:20:19 volumio volumio[984]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 16:20:20 volumio sudo[3828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 16:19 Jul 03 16:20:20 volumio sudo[3828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:20 volumio sudo[3828]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:22 volumio volumio-remote-updater[699]: [2025-07-03 16:20:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 03 16:20:22 volumio volumio-remote-updater[699]: [2025-07-03 16:20:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 03 16:20:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 03 16:20:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 03 16:20:22 volumio systemd[1]: Started dynamicswap service. Jul 03 16:20:23 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 03 16:20:23 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 03 16:20:23 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jul 03 16:20:23 volumio systemd[1]: Stopped Volumio Backend Module. Jul 03 16:20:23 volumio systemd[1]: Started Volumio Backend Module. Jul 03 16:20:23 volumio systemd[1]: Started dynamicswap service. Jul 03 16:20:23 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 03 16:20:25 volumio volumio[3843]: info: ------------------------------------------- Jul 03 16:20:25 volumio volumio[3843]: info: ----- Volumio3 ---- Jul 03 16:20:25 volumio volumio[3843]: info: ------------------------------------------- Jul 03 16:20:25 volumio volumio[3843]: info: ----- System startup ---- Jul 03 16:20:25 volumio volumio[3843]: info: ------------------------------------------- Jul 03 16:20:26 volumio volumio[3843]: info: MYVOLUMIO Environment detected Jul 03 16:20:26 volumio volumio[3843]: info: Plugin folders cleanup Jul 03 16:20:26 volumio volumio[3843]: info: Scanning into folder /volumio/app/plugins/ Jul 03 16:20:26 volumio volumio[3843]: info: Scanning category audio_interface Jul 03 16:20:26 volumio volumio[3843]: info: Scanning category miscellanea Jul 03 16:20:26 volumio volumio[3843]: info: Scanning category music_service Jul 03 16:20:26 volumio volumio[3843]: info: Scanning category plugins.json Jul 03 16:20:26 volumio volumio[3843]: info: Scanning category system_controller Jul 03 16:20:26 volumio volumio[3843]: info: Scanning category user_interface Jul 03 16:20:26 volumio volumio[3843]: info: Scanning into folder /data/plugins/ Jul 03 16:20:26 volumio volumio[3843]: info: Scanning category music_service Jul 03 16:20:26 volumio volumio[3843]: info: Scanning category user_interface Jul 03 16:20:26 volumio volumio[3843]: info: Plugin folders cleanup completed Jul 03 16:20:26 volumio volumio[3843]: info: ------------------------------------------- Jul 03 16:20:26 volumio volumio[3843]: info: ----- Core plugins startup ---- Jul 03 16:20:26 volumio volumio[3843]: info: ------------------------------------------- Jul 03 16:20:26 volumio volumio[3843]: info: Loading plugins from folder /volumio/app/plugins/ Jul 03 16:20:26 volumio volumio[3843]: info: Adding plugin upnp to MyMusic Plugins Jul 03 16:20:26 volumio volumio[3843]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 03 16:20:26 volumio volumio[3843]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 03 16:20:26 volumio volumio[3843]: info: Loading plugins from folder /data/plugins/ Jul 03 16:20:26 volumio volumio[3843]: info: Loading plugin "system"... Jul 03 16:20:26 volumio volumio[3843]: info: Loading plugin "appearance"... Jul 03 16:20:26 volumio kernel: usb 1-4: device descriptor read/64, error -110 Jul 03 16:20:26 volumio kernel: usb 1-4: new full-speed USB device number 30 using ohci-pci Jul 03 16:20:27 volumio volumio[3843]: info: Loading plugin "network"... Jul 03 16:20:27 volumio volumio[3843]: info: Refreshing Cached IP Addresses Jul 03 16:20:27 volumio sudo[3872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:20:27 volumio sudo[3872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:27 volumio sudo[3870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 16:20:27 volumio sudo[3870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:27 volumio volumio[3843]: info: Loading plugin "services"... Jul 03 16:20:27 volumio sudo[3872]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:27 volumio sudo[3870]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:27 volumio volumio[3843]: info: Loading plugin "alsa_controller"... Jul 03 16:20:27 volumio sudo[3876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 03 16:20:27 volumio sudo[3876]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:27 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:20:27 volumio volumio-remote-updater[699]: [2025-07-03 16:20:27] [connect] Successful connection Jul 03 16:20:27 volumio volumio[3843]: info: Loading plugin "wizard"... Jul 03 16:20:27 volumio volumio[3843]: info: Loading plugin "networkfs"... Jul 03 16:20:27 volumio volumio[3843]: info: Starting Udev Watcher for removable devices Jul 03 16:20:27 volumio volumio[3843]: info: Ignoring mount for partition: boot Jul 03 16:20:27 volumio volumio[3843]: info: Ignoring mount for partition: volumio Jul 03 16:20:27 volumio volumio[3843]: info: Ignoring mount for partition: volumio_data Jul 03 16:20:28 volumio volumio[3843]: info: Mounting Device ____ Jul 03 16:20:28 volumio sudo[3886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/____ -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 03 16:20:28 volumio sudo[3886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:30 volumio sudo[3876]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:30 volumio sudo[3886]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:30 volumio volumio[3843]: info: Mounting Device ____ Jul 03 16:20:30 volumio sudo[3893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/____ -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 03 16:20:30 volumio sudo[3893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:31 volumio sudo[3893]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:31 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 16:20:31 volumio volumio[3843]: info: Loading plugin "volumio_command_line_client"... Jul 03 16:20:31 volumio volumio[3843]: info: Loading plugin "upnp"... Jul 03 16:20:31 volumio volumio[3843]: info: [1751530831264] Starting Upmpd Daemon Jul 03 16:20:31 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 16:20:31 volumio volumio[3843]: info: Loading plugin "my_music"... Jul 03 16:20:31 volumio volumio[3843]: info: Loading plugin "mpd"... Jul 03 16:20:31 volumio volumio[3843]: info: Plugin upnp_browser is not enabled Jul 03 16:20:31 volumio volumio[3843]: info: Loading plugin "alarm-clock"... Jul 03 16:20:31 volumio volumio[3843]: info: Loading plugin "airplay_emulation"... Jul 03 16:20:31 volumio volumio[3843]: info: Starting Shairport Sync Jul 03 16:20:31 volumio volumio[3843]: info: Loading plugin "last_100"... Jul 03 16:20:31 volumio volumio[3843]: info: Loading plugin "webradio"... Jul 03 16:20:32 volumio volumio[3843]: info: Loading plugin "i2s_dacs"... Jul 03 16:20:32 volumio volumio[3843]: info: I2S DAC not set, start Auto-detection Jul 03 16:20:32 volumio volumio[3843]: info: Loading plugin "volumiodiscovery"... Jul 03 16:20:32 volumio volumio[3843]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 16:20:32 volumio volumio[3843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 16:20:32 volumio node[3843]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 16:20:32 volumio volumio[3843]: *** WARNING *** For more information see Jul 03 16:20:32 volumio volumio[3843]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 16:20:32 volumio volumio[3843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 16:20:32 volumio volumio[3843]: *** WARNING *** For more information see Jul 03 16:20:32 volumio node[3843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 16:20:32 volumio node[3843]: *** WARNING *** For more information see Jul 03 16:20:32 volumio node[3843]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 16:20:32 volumio node[3843]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 16:20:32 volumio node[3843]: *** WARNING *** For more information see Jul 03 16:20:32 volumio volumio[3843]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 03 16:20:32 volumio volumio[3843]: info: Discovery: Started advertising with name: Volumio Jul 03 16:20:32 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 16:20:32 volumio volumio[3843]: info: Loading plugin "peppy_screensaver"... Jul 03 16:20:32 volumio volumio[3843]: info: Applying required configuration parameters for plugin peppy_screensaver Jul 03 16:20:32 volumio volumio[3843]: info: Loading plugin "outputs"... Jul 03 16:20:32 volumio volumio[3843]: info: Loading plugin "albumart"... Jul 03 16:20:32 volumio volumio[3843]: info: Plugin example_plugin is not enabled Jul 03 16:20:32 volumio volumio[3843]: info: Loading plugin "inputs"... Jul 03 16:20:32 volumio volumio[3843]: info: Loading plugin "updater_comm"... Jul 03 16:20:33 volumio volumio[3843]: info: Plugin mpdemulation is not enabled Jul 03 16:20:33 volumio volumio[3843]: info: Loading plugin "rest_api"... Jul 03 16:20:33 volumio volumio[3843]: info: Loading plugin "websocket"... Jul 03 16:20:33 volumio volumio[3843]: Forking 1 albumart workers Jul 03 16:20:33 volumio volumio[3843]: info: Starting Socket.io Server version 2.3.0 Jul 03 16:20:33 volumio volumio[3843]: info: Loading plugin "RoonBridge"... Jul 03 16:20:33 volumio volumio[3843]: info: Applying required configuration parameters for plugin RoonBridge Jul 03 16:20:33 volumio volumio[3843]: info: Loading i18n strings for locale zh Jul 03 16:20:33 volumio volumio[3843]: Updating browse sources language Jul 03 16:20:33 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::initPlayerControls Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: Express server listening on port 3000 Jul 03 16:20:34 volumio volumio[3843]: [Metrics] WebUI: 9s 789.83ms Jul 03 16:20:34 volumio volumio[3843]: info: Setting Device type: x86 Jul 03 16:20:34 volumio volumio[3843]: info: CoreStateMachine::resetVolumioState Jul 03 16:20:34 volumio volumio[3843]: info: CoreStateMachine::getcurrentVolume Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 16:20:34 volumio volumio[3843]: Starting albumart workers Jul 03 16:20:34 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:34 volumio volumio[3843]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 03 16:20:34 volumio volumio[3843]: info: Completed loading Core Plugins Jul 03 16:20:34 volumio volumio[3843]: info: Preparing to generate the ALSA configuration file Jul 03 16:20:34 volumio volumio[3843]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 03 16:20:34 volumio volumio[3843]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 03 16:20:34 volumio volumio[3843]: info: Reading ALSA contributions from plugins. Jul 03 16:20:34 volumio volumio[3843]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 1 Jul 03 16:20:34 volumio volumio[3843]: 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: 2 Jul 03 16:20:34 volumio volumio[3843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 03 16:20:34 volumio volumio[3843]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Jul 03 16:20:34 volumio volumio[3843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 03 16:20:34 volumio volumio[3843]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Jul 03 16:20:35 volumio volumio[3843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 03 16:20:35 volumio volumio[3843]: info: VolumeController:: Volume=0 Mute =false Jul 03 16:20:35 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:20:35 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:35 volumio volumio[3843]: info: CoreStateMachine::updateTrackBlock Jul 03 16:20:35 volumio volumio[3843]: info: CorePlayQueue::getTrackBlock Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 16:20:35 volumio volumio[3843]: info: Reloading queue from file Jul 03 16:20:35 volumio volumio[3843]: info: CoreStateMachine::setRepeat null single undefined Jul 03 16:20:35 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:20:35 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:35 volumio volumio[3843]: info: CoreStateMachine::setRandom null Jul 03 16:20:35 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:20:35 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:35 volumio volumio[3843]: 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 Jul 03 16:20:35 volumio volumio[3843]: info: VolumeController:: Volume=0 Mute =false Jul 03 16:20:35 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:20:35 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:35 volumio volumio[3843]: info: Asound.conf file unchanged, so no further update is needed Jul 03 16:20:35 volumio volumio[3843]: info: Output device has changed, restarting MPD Jul 03 16:20:35 volumio sudo[3934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 16:20:35 volumio sudo[3934]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:35 volumio sudo[3934]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:35 volumio volumio[3843]: info: Output device has changed, restarting Shairport Sync Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:35 volumio sudo[3937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 16:20:35 volumio sudo[3937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:35 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 16:20:35 volumio volumio[3843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 16:20:35 volumio volumio[3843]: info: ___________ START PLUGINS ___________ Jul 03 16:20:35 volumio volumio[3843]: info: ControllerMpd::onStart: Initializing MPD Jul 03 16:20:35 volumio volumio[3843]: info: Creating MPD Configuration file Jul 03 16:20:35 volumio systemd[1]: mpd.service: Succeeded. Jul 03 16:20:35 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 16:20:35 volumio sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 16:20:35 volumio sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:35 volumio systemd[1]: Starting Music Player Daemon... Jul 03 16:20:35 volumio sudo[3943]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:35 volumio volumio[3843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 16:20:35 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:36 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:36 volumio sudo[3945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 16:20:36 volumio sudo[3945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:36 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 03 16:20:36 volumio systemd[1]: mpd.service: Succeeded. Jul 03 16:20:36 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 16:20:36 volumio volumio[3843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 16:20:36 volumio systemd[1]: Starting Music Player Daemon... Jul 03 16:20:36 volumio volumio[3843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 16:20:36 volumio volumio[3843]: info: [1751530836126] CoreMusicLibrary::Adding element Last_100 Jul 03 16:20:36 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:20:36 volumio volumio[3843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 16:20:36 volumio volumio[3843]: info: [1751530836134] CoreMusicLibrary::Adding element Webradio Jul 03 16:20:36 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:20:36 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:20:36 volumio volumio[3843]: info: Initializing BBC Radios Jul 03 16:20:36 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:20:36 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:36 volumio volumio[3843]: info: Loading i18n strings for locale zh Jul 03 16:20:36 volumio sudo[3956]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 16:20:36 volumio sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:36 volumio sudo[3956]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:36 volumio volumio[3843]: info: /tmp/myfifo created Jul 03 16:20:36 volumio volumio[3843]: info: /tmp/myfifosa created Jul 03 16:20:36 volumio sudo[3975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jul 03 16:20:36 volumio sudo[3975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:36 volumio sudo[3975]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:36 volumio volumio[3843]: info: snd-dummy loaded Jul 03 16:20:36 volumio volumio[3843]: error: Plugin peppy_screensaver failed to start! Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jul 03 16:20:36 volumio volumio[3843]: info: Volumio Calling Home Jul 03 16:20:36 volumio sudo[3978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jul 03 16:20:36 volumio sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:36 volumio sudo[3978]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:37 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:37 volumio volumio[3843]: info: Discovery: adding 492524ff-0d18-4e9b-b0b7-e00a3046faf7 Jul 03 16:20:37 volumio volumio[3843]: info: Discovery: Found device Volumio Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:37 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:37 volumio volumio[3843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 03 16:20:37 volumio volumio[3843]: info: MPD Permissions set Jul 03 16:20:37 volumio volumio[3843]: info: MPD Permissions set Jul 03 16:20:37 volumio volumio[3843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:37 volumio volumio[3843]: info: Starting Shairport Sync Jul 03 16:20:37 volumio volumio[3843]: info: Starting Shairport Sync Jul 03 16:20:37 volumio sudo[4004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 16:20:37 volumio sudo[4004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 16:20:37 volumio volumio[3843]: info: Starting Shairport Sync Jul 03 16:20:37 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 03 16:20:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 16:20:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 16:20:37 volumio sudo[4004]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:37 volumio sudo[4007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 16:20:37 volumio sudo[4007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 16:20:37 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 03 16:20:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 16:20:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 16:20:37 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:37 volumio sudo[4007]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:37 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:37 volumio sudo[4013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 16:20:37 volumio sudo[4013]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 16:20:38 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 03 16:20:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 16:20:38 volumio volumio[3843]: info: Shairport-Sync Started Jul 03 16:20:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 16:20:38 volumio sudo[4013]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:38 volumio volumio[3843]: Error adding Membership: Error: addMembership EINVAL Jul 03 16:20:38 volumio volumio[3843]: info: Shairport-Sync Started Jul 03 16:20:38 volumio volumio[3843]: info: Volumio called home Jul 03 16:20:38 volumio volumio[3843]: info: Shairport-Sync Started Jul 03 16:20:39 volumio mpd[3970]: Jul 03 16:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 16:20:39 volumio volumio[3843]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 11 Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:39 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 16:20:39 volumio systemd[1]: Started Music Player Daemon. Jul 03 16:20:39 volumio sudo[3937]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:39 volumio sudo[3945]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 16:20:39 volumio volumio[3843]: info: Received Get System Info Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:20:39 volumio volumio[3843]: info: Discovery: Getting this device information Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:39 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:20:39 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:39 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:39 volumio volumio[3843]: info: Listing playlists Jul 03 16:20:39 volumio volumio[3843]: info: Completed starting Core Plugins Jul 03 16:20:39 volumio volumio[3843]: info: ------------------------------------------- Jul 03 16:20:39 volumio volumio[3843]: info: ----- MyVolumio plugins startup ---- Jul 03 16:20:39 volumio volumio[3843]: info: ------------------------------------------- Jul 03 16:20:39 volumio volumio[3843]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 03 16:20:39 volumio volumio[3843]: info: MPD running with PID3970 Jul 03 16:20:39 volumio volumio[3843]: ,establishing connection Jul 03 16:20:39 volumio volumio[3843]: error: MPD error: The expression evaluated to a falsy value: Jul 03 16:20:39 volumio volumio[3843]: assert.ok(self.idling) Jul 03 16:20:39 volumio volumio[3843]: error: The expression evaluated to a falsy value: Jul 03 16:20:39 volumio volumio[3843]: assert.ok(self.idling) Jul 03 16:20:39 volumio volumio[3843]: error: MPD error: The expression evaluated to a falsy value: Jul 03 16:20:39 volumio volumio[3843]: assert.ok(self.idling) Jul 03 16:20:39 volumio volumio[3843]: error: The expression evaluated to a falsy value: Jul 03 16:20:39 volumio volumio[3843]: assert.ok(self.idling) Jul 03 16:20:39 volumio volumio[3843]: error: updateQueue error: null Jul 03 16:20:40 volumio volumio-remote-updater[699]: [2025-07-03 16:20:40] [connect] Successful connection Jul 03 16:20:40 volumio volumio-remote-updater[699]: [2025-07-03 16:20:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751530840 101 Jul 03 16:20:40 volumio volumio[3843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 12 Jul 03 16:20:41 volumio volumio[3843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jul 03 16:20:41 volumio sudo[4027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 16:20:41 volumio sudo[4027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:41 volumio sudo[4027]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 16:20:41 volumio sudo[4030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:20:41 volumio sudo[4030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:41 volumio sudo[4030]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:41 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:41 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 16:20:41 volumio volumio[3843]: info: Received Get System Info Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:20:41 volumio volumio[3843]: info: Discovery: Getting this device information Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:41 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:20:41 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:41 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:41 volumio volumio[3843]: info: Listing playlists Jul 03 16:20:41 volumio sudo[4034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 03 16:20:41 volumio sudo[4034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:41 volumio sudo[4034]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:41 volumio volumio[3843]: info: Upmpdcli Daemon Started Jul 03 16:20:42 volumio kernel: usb 1-4: device descriptor read/64, error -110 Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 03 16:20:43 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 16:20:48 volumio volumio[3843]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 03 16:20:48 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 03 16:20:48 volumio volumio[3843]: info: Preparing to save Alsa Options, stopping services first Jul 03 16:20:48 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:48 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:48 volumio volumio[3843]: info: CoreCommandRouter::volumioPause Jul 03 16:20:48 volumio volumio[3843]: info: CoreStateMachine::pause Jul 03 16:20:48 volumio volumio[3843]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB AUDIO DAC"}} Jul 03 16:20:48 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:48 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:49 volumio volumio[3843]: info: Setting mixer PCM for card USB AUDIO DAC Jul 03 16:20:49 volumio volumio[3843]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 03 16:20:49 volumio volumio[3843]: info: Updating Volume Controller Parameters: Device: 5 Name: USB AUDIO DAC Mixer: PCM Max Vol: 100 Vol Curve; linear Vol Steps: 2 Jul 03 16:20:49 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 03 16:20:49 volumio volumio[3843]: info: Disabling external Volume Control Jul 03 16:20:49 volumio volumio[3843]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 16:20:49 volumio volumio[3843]: info: Preparing to generate the ALSA configuration file Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 03 16:20:49 volumio volumio[3843]: info: Adding plugin bluetooth to MyMusic Plugins Jul 03 16:20:49 volumio volumio[3843]: info: Adding plugin multiroom to MyMusic Plugins Jul 03 16:20:49 volumio volumio[3843]: info: Adding plugin metavolumio to MyMusic Plugins Jul 03 16:20:49 volumio volumio[3843]: info: Adding plugin cd_controller to MyMusic Plugins Jul 03 16:20:49 volumio volumio[3843]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 03 16:20:49 volumio volumio[3843]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 03 16:20:49 volumio volumio[3843]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 03 16:20:50 volumio volumio[3843]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 03 16:20:50 volumio volumio[3843]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 03 16:20:50 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:50 volumio volumio[3843]: info: Starting MyVolumio Remote Streaming Endpoints Jul 03 16:20:50 volumio volumio[3843]: info: MyVolumio login type: Token Jul 03 16:20:50 volumio volumio[3843]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 03 16:20:50 volumio volumio[3843]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 03 16:20:51 volumio volumio[3843]: info: Starting Streaming Service Transparent Proxy Jul 03 16:20:51 volumio volumio[3843]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 03 16:20:51 volumio volumio[3843]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 03 16:20:51 volumio volumio[3843]: info: Streaming services startup Jul 03 16:20:51 volumio volumio[3843]: info: Starting Streaming Daemon Jul 03 16:20:51 volumio sudo[4057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 16:20:51 volumio sudo[4057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:51 volumio volumio[3843]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 03 16:20:51 volumio sudo[4057]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:51 volumio volumio[3843]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 03 16:20:51 volumio volumio[3843]: info: Reading ALSA contributions from plugins. Jul 03 16:20:51 volumio volumio[3843]: error: Cannot start Volumio Streaming Daemon Jul 03 16:20:51 volumio volumio[3843]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 03 16:20:51 volumio volumio[3843]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 03 16:20:51 volumio volumio[3843]: info: VolumeController:: Volume=0 Mute =false Jul 03 16:20:51 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:20:51 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:51 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 16:20:52 volumio volumio[3843]: info: Asound.conf file unchanged, so no further update is needed Jul 03 16:20:52 volumio volumio[3843]: info: Output device has changed, restarting MPD Jul 03 16:20:52 volumio volumio[3843]: info: Output device has changed, restarting Shairport Sync Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:52 volumio sudo[4072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 16:20:52 volumio sudo[4072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:52 volumio sudo[4074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 16:20:52 volumio sudo[4074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:52 volumio sudo[4072]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:52 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 16:20:52 volumio volumio[3843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 16:20:52 volumio volumio[3843]: info: MPD Permissions set Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:52 volumio systemd[1]: mpd.service: Succeeded. Jul 03 16:20:52 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 16:20:52 volumio systemd[1]: Starting Music Player Daemon... Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 03 16:20:52 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:20:52 volumio volumio[3843]: info: Starting Shairport Sync Jul 03 16:20:52 volumio sudo[4088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 16:20:52 volumio sudo[4088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 03 16:20:52 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 03 16:20:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 03 16:20:52 volumio sudo[4080]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 16:20:52 volumio sudo[4080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:20:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 03 16:20:52 volumio sudo[4088]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:52 volumio sudo[4080]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:52 volumio volumio[3843]: info: Shairport-Sync Started Jul 03 16:20:53 volumio volumio[3843]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 03 16:20:53 volumio volumio[3843]: info: CoreStateMachine::getcurrentVolume Jul 03 16:20:53 volumio volumio[3843]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 16:20:53 volumio volumio[3843]: info: VolumeController:: Volume=0 Mute =false Jul 03 16:20:53 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:20:53 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:53 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:53 volumio volumio[3843]: info: CoreStateMachine::updateTrackBlock Jul 03 16:20:53 volumio volumio[3843]: info: CorePlayQueue::getTrackBlock Jul 03 16:20:53 volumio volumio[3843]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 16:20:53 volumio volumio[3843]: STREAMING PROXY: Starting server on port 3245 Jul 03 16:20:53 volumio volumio[3843]: Node JS runtime: 14 Jul 03 16:20:53 volumio volumio[3843]: info: VolumeController:: Volume=0 Mute =false Jul 03 16:20:53 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:20:53 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:53 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:55 volumio mpd[4093]: Jul 03 16:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 16:20:55 volumio systemd[1]: Started Music Player Daemon. Jul 03 16:20:55 volumio sudo[4074]: pam_unix(sudo:session): session closed for user root Jul 03 16:20:55 volumio volumio[3843]: error: updateQueue error: null Jul 03 16:20:56 volumio volumio[3843]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:20:56 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:56 volumio volumio[3843]: info: Restoring Previous Volume level: false false Jul 03 16:20:56 volumio volumio[3843]: info: Volume configurations have been set Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 03 16:20:56 volumio volumio[3843]: info: Updating Volume Controller Parameters: Device: 5 Name: USB AUDIO DAC Mixer: PCM Max Vol: 100 Vol Curve; linear Vol Steps: 2 Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 03 16:20:56 volumio volumio[3843]: info: Disabling external Volume Control Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 16:20:56 volumio volumio[3843]: info: VolumeController:: Volume=0 Mute =false Jul 03 16:20:56 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:20:56 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:20:56 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:20:58 volumio kernel: usb 1-4: device descriptor read/64, error -110 Jul 03 16:20:58 volumio kernel: usb usb1-port4: attempt power cycle Jul 03 16:20:58 volumio kernel: usb 3-4: new high-speed USB device number 43 using ehci-pci Jul 03 16:21:00 volumio volumio[3843]: info: VolumeController::SetAlsaVolumeNaN Jul 03 16:21:00 volumio volumio[3843]: info: CoreStateMachine::pushState Jul 03 16:21:00 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:21:00 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 16:21:00 volumio volumio[3843]: info: CoreCommandRouter::volumioPushState Jul 03 16:21:02 volumio volumio[3843]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jul 03 16:21:02 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jul 03 16:21:02 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 03 16:21:02 volumio sudo[4124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 16:21:02 volumio sudo[4124]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:02 volumio sudo[4124]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:02 volumio sudo[4127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 16:21:02 volumio sudo[4127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:02 volumio volumio[3843]: info: MPD Permissions set Jul 03 16:21:02 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:02 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 16:21:02 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:02 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:02 volumio systemd[1]: mpd.service: Succeeded. Jul 03 16:21:02 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 16:21:02 volumio systemd[1]: Starting Music Player Daemon... Jul 03 16:21:02 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:21:02 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 03 16:21:02 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:02 volumio sudo[4130]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 16:21:02 volumio sudo[4130]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:02 volumio sudo[4130]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:03 volumio kernel: usb 3-4: device descriptor read/64, error -110 Jul 03 16:21:04 volumio mpd[4138]: Jul 03 16:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 16:21:04 volumio systemd[1]: Started Music Player Daemon. Jul 03 16:21:04 volumio sudo[4127]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:04 volumio volumio[3843]: error: updateQueue error: null Jul 03 16:21:05 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 16:21:05 volumio volumio[3843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 03 16:21:05 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:21:05 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:21:06 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:21:06 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:21:06 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 16:21:08 volumio volumio[3843]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 03 16:21:08 volumio sudo[4145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 16:21:08 volumio sudo[4145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:08 volumio sudo[4145]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:08 volumio sudo[4148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 16:21:08 volumio sudo[4148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:08 volumio systemd[1]: Stopping Music Player Daemon... Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 03 16:21:08 volumio systemd[1]: mpd.service: Succeeded. Jul 03 16:21:08 volumio systemd[1]: Stopped Music Player Daemon. Jul 03 16:21:08 volumio systemd[1]: Starting Music Player Daemon... Jul 03 16:21:08 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:08 volumio volumio[3843]: info: MPD Permissions set Jul 03 16:21:09 volumio sudo[4155]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 16:21:09 volumio sudo[4155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:09 volumio sudo[4155]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:10 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:10 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:10 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 03 16:21:10 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 16:21:10 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 03 16:21:10 volumio volumio[3843]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 16:21:10 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:21:10 volumio mpd[4158]: Jul 03 16:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 16:21:11 volumio systemd[1]: Started Music Player Daemon. Jul 03 16:21:11 volumio sudo[4148]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:11 volumio volumio[3843]: error: updateQueue error: null Jul 03 16:21:11 volumio volumio[3843]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 03 16:21:11 volumio volumio[3843]: info: Completed starting MyVolumio Plugin Jul 03 16:21:11 volumio volumio[3843]: info: BOOT COMPLETED Jul 03 16:21:11 volumio volumio[3843]: [Metrics] CommandRouter: 46s 498.37ms Jul 03 16:21:11 volumio volumio[3843]: info: CoreCommandRouter::volumiosetStartupVolume Jul 03 16:21:11 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:21:11 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:21:11 volumio volumio[3843]: info: CoreCommandRouter::Close All Modals sent Jul 03 16:21:11 volumio volumio[3843]: info: CoreCommandRouter::Close All Modals sent Jul 03 16:21:12 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 03 16:21:12 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 03 16:21:12 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 03 16:21:18 volumio volumio[3843]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 16:21:18 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 16:21:19 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 03 16:21:19 volumio sudo[4218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 03 16:21:19 volumio sudo[4218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:19 volumio sudo[4218]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:19 volumio sudo[4224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 03 16:21:19 volumio sudo[4224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:19 volumio sudo[4224]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:19 volumio sudo[4233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 03 16:21:19 volumio sudo[4233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:19 volumio sudo[4233]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:19 volumio sudo[4238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 03 16:21:19 volumio sudo[4238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:19 volumio sudo[4238]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:19 volumio sudo[4243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 16:21:19 volumio sudo[4243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:19 volumio sudo[4243]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:19 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 03 16:21:19 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 03 16:21:19 volumio sudo[4247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:21:19 volumio sudo[4247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:19 volumio sudo[4247]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:19 volumio kernel: usb 3-4: device descriptor read/64, error -110 Jul 03 16:21:19 volumio sudo[4250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 03 16:21:19 volumio sudo[4250]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:19 volumio kernel: usb 3-4: new high-speed USB device number 44 using ehci-pci Jul 03 16:21:21 volumio volumio[3843]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 03 16:21:21 volumio volumio[3843]: info: Retrying Login Due to Network Error Jul 03 16:21:22 volumio sudo[4250]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:24 volumio kernel: usb 3-4: device descriptor read/64, error -110 Jul 03 16:21:27 volumio volumio[3843]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Jul 03 16:21:27 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Jul 03 16:21:27 volumio sudo[4253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jul 03 16:21:27 volumio sudo[4253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:27 volumio sudo[4253]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:27 volumio sudo[4256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jul 03 16:21:27 volumio sudo[4256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:27 volumio sudo[4256]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:27 volumio volumio[3843]: info: Permissions for /etc/network/interfaces set Jul 03 16:21:27 volumio volumio[3843]: info: Restarting Wired Network Jul 03 16:21:27 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 03 16:21:27 volumio volumio[3843]: info: Discovery: Restarting Advertising due to device name change Jul 03 16:21:27 volumio sudo[4259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Jul 03 16:21:27 volumio sudo[4259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:27 volumio sudo[4259]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:27 volumio sudo[4261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Jul 03 16:21:27 volumio sudo[4261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:27 volumio sudo[4261]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:27 volumio sudo[4263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Jul 03 16:21:27 volumio sudo[4263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:27 volumio kernel: RTL8211DN Gigabit Ethernet r8169-0-200:00: attached PHY driver (mii_bus:phy_addr=r8169-0-200:00, irq=MAC) Jul 03 16:21:28 volumio sudo[4263]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:28 volumio kernel: r8169 0000:02:00.0 eth0: Link is Down Jul 03 16:21:28 volumio volumio[3843]: info: Discovery: A device disappeared from network Jul 03 16:21:28 volumio volumio[3843]: info: Discovery: Device volumio disappeared from network Jul 03 16:21:30 volumio volumio[3843]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Jul 03 16:21:30 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Jul 03 16:21:30 volumio sudo[4268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jul 03 16:21:30 volumio sudo[4268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:30 volumio sudo[4268]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:30 volumio sudo[4271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jul 03 16:21:30 volumio sudo[4271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:30 volumio sudo[4271]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:30 volumio volumio[3843]: info: Permissions for /etc/network/interfaces set Jul 03 16:21:30 volumio volumio[3843]: info: Restarting Wireless Network Jul 03 16:21:30 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 03 16:21:30 volumio volumio[3843]: info: Discovery: Restarting Advertising due to device name change Jul 03 16:21:30 volumio sudo[4274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 03 16:21:30 volumio sudo[4274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:30 volumio systemd[1]: Stopping Wireless Services... Jul 03 16:21:30 volumio wpa_supplicant[909]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Jul 03 16:21:30 volumio wpa_supplicant[909]: nl80211: Failed to set IPv4 unicast in multicast filter Jul 03 16:21:30 volumio wpa_supplicant[909]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Jul 03 16:21:30 volumio wpa_supplicant[909]: nl80211: Failed to set IPv4 unicast in multicast filter Jul 03 16:21:30 volumio wpa_supplicant[909]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Jul 03 16:21:30 volumio wpa_supplicant[909]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Jul 03 16:21:30 volumio dhcpcd[785]: wlan0: carrier lost Jul 03 16:21:30 volumio kernel: wlan0: deauthenticating from bc:54:fc:f7:8f:d3 by local choice (Reason: 3=DEAUTH_LEAVING) Jul 03 16:21:30 volumio wpa_supplicant[909]: wlan0: CTRL-EVENT-DISCONNECTED bssid=bc:54:fc:f7:8f:d3 reason=3 locally_generated=1 Jul 03 16:21:30 volumio avahi-daemon[732]: Withdrawing address record for 192.168.0.104 on wlan0. Jul 03 16:21:30 volumio avahi-daemon[732]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.104. Jul 03 16:21:30 volumio avahi-daemon[732]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 03 16:21:30 volumio dhcpcd[785]: wlan0: deleting route to 192.168.0.0/24 Jul 03 16:21:30 volumio dhcpcd[785]: wlan0: deleting default route via 192.168.0.1 Jul 03 16:21:30 volumio wpa_supplicant[909]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 03 16:21:30 volumio wpa_supplicant[909]: wlan0: CTRL-EVENT-TERMINATING Jul 03 16:21:30 volumio systemd[1]: wireless.service: Succeeded. Jul 03 16:21:30 volumio systemd[1]: Stopped Wireless Services. Jul 03 16:21:30 volumio systemd[1]: Starting Wireless Services... Jul 03 16:21:31 volumio wireless.js[4298]: Cleaning previous... Jul 03 16:21:31 volumio sudo[4313]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 03 16:21:31 volumio sudo[4313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:31 volumio sudo[4313]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:31 volumio sudo[4315]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 03 16:21:31 volumio sudo[4315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:31 volumio sudo[4315]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:31 volumio wireless.js[4298]: Stopped aP Jul 03 16:21:31 volumio sudo[4322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 03 16:21:31 volumio sudo[4322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:31 volumio volumio[3843]: info: MyVolumio login type: Token Jul 03 16:21:31 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:31 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:31 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:31 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:31 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:31 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:31 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:31 volumio sudo[4322]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:31 volumio volumio[3843]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 03 16:21:31 volumio volumio[3843]: info: Retrying Login Due to Network Error Jul 03 16:21:31 volumio sudo[4324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 03 16:21:31 volumio sudo[4324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:31 volumio sudo[4324]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:31 volumio sudo[4331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 03 16:21:31 volumio sudo[4331]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:31 volumio sudo[4331]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:32 volumio sudo[4333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 03 16:21:32 volumio sudo[4333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:32 volumio volumio[3843]: info: Discovery: Started advertising with name: Volumio Jul 03 16:21:33 volumio ntpd[1618]: Deleting interface #3 wlan0, 192.168.0.104#123, interface stats: received=152, sent=164, dropped=0, active_time=1176 secs Jul 03 16:21:33 volumio ntpd[1618]: 108.59.2.24 local addr 192.168.0.104 -> Jul 03 16:21:33 volumio ntpd[1618]: 162.159.200.1 local addr 192.168.0.104 -> Jul 03 16:21:33 volumio ntpd[1618]: 117.80.112.205 local addr 192.168.0.104 -> Jul 03 16:21:33 volumio ntpd[1618]: 202.118.1.81 local addr 192.168.0.104 -> Jul 03 16:21:33 volumio ntpd[1618]: 193.182.111.12 local addr 192.168.0.104 -> Jul 03 16:21:33 volumio ntpd[1618]: 202.118.1.130 local addr 192.168.0.104 -> Jul 03 16:21:34 volumio sudo[4333]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:34 volumio wireless.js[4298]: Start wireless flow Jul 03 16:21:34 volumio wireless.js[4298]: Stopped hotspot (if there).. Jul 03 16:21:34 volumio sudo[4345]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 03 16:21:34 volumio sudo[4345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:34 volumio sudo[4345]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:34 volumio sudo[4347]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 03 16:21:34 volumio sudo[4347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:34 volumio sudo[4347]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:34 volumio wireless.js[4298]: DHCP IP Jul 03 16:21:34 volumio wireless.js[4298]: Start ap Jul 03 16:21:34 volumio wpa_supplicant[4349]: Successfully initialized wpa_supplicant Jul 03 16:21:34 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:34 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:34 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:34 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:34 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:34 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:34 volumio kernel: ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001) Jul 03 16:21:34 volumio sudo[4350]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 03 16:21:34 volumio sudo[4350]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:34 volumio dhcpcd[4351]: sending commands to master dhcpcd process Jul 03 16:21:34 volumio dhcpcd[785]: control command: /sbin/dhcpcd Jul 03 16:21:34 volumio sudo[4350]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:35 volumio wireless.js[4298]: trying... Jul 03 16:21:35 volumio sudo[4357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:35 volumio sudo[4357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:35 volumio sudo[4357]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:35 volumio volumio[3843]: info: Discovery: Started advertising with name: Volumio Jul 03 16:21:36 volumio wireless.js[4298]: trying... Jul 03 16:21:36 volumio sudo[4361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:36 volumio sudo[4361]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:36 volumio sudo[4361]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:36 volumio wpa_supplicant[4354]: wlan0: SME: Trying to authenticate with bc:54:fc:f7:8f:d3 (SSID='MERCURY_8FD3' freq=2437 MHz) Jul 03 16:21:36 volumio kernel: wlan0: authenticate with bc:54:fc:f7:8f:d3 Jul 03 16:21:36 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Jul 03 16:21:36 volumio kernel: wlan0: send auth to bc:54:fc:f7:8f:d3 (try 1/3) Jul 03 16:21:36 volumio wpa_supplicant[4354]: wlan0: Trying to associate with bc:54:fc:f7:8f:d3 (SSID='MERCURY_8FD3' freq=2437 MHz) Jul 03 16:21:36 volumio kernel: wlan0: authenticated Jul 03 16:21:36 volumio kernel: wlan0: associate with bc:54:fc:f7:8f:d3 (try 1/3) Jul 03 16:21:36 volumio kernel: wlan0: RX AssocResp from bc:54:fc:f7:8f:d3 (capab=0x1411 status=0 aid=4) Jul 03 16:21:36 volumio kernel: wlan0: associated Jul 03 16:21:36 volumio wpa_supplicant[4354]: wlan0: Associated with bc:54:fc:f7:8f:d3 Jul 03 16:21:36 volumio wpa_supplicant[4354]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 03 16:21:36 volumio wpa_supplicant[4354]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jul 03 16:21:36 volumio kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by bc:54:fc:f7:8f:d3 Jul 03 16:21:36 volumio wpa_supplicant[4354]: wlan0: WPA: Key negotiation completed with bc:54:fc:f7:8f:d3 [PTK=CCMP GTK=CCMP] Jul 03 16:21:36 volumio wpa_supplicant[4354]: wlan0: CTRL-EVENT-CONNECTED - Connection to bc:54:fc:f7:8f:d3 completed [id=0 id_str=] Jul 03 16:21:36 volumio dhcpcd[785]: wlan0: carrier acquired Jul 03 16:21:36 volumio dhcpcd[785]: wlan0: confile file changed, expiring leases Jul 03 16:21:36 volumio dhcpcd[785]: wlan0: IAID 85:09:1c:cb Jul 03 16:21:37 volumio wireless.js[4298]: trying... Jul 03 16:21:37 volumio dhcpcd[785]: wlan0: soliciting an IPv6 router Jul 03 16:21:37 volumio sudo[4365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:37 volumio sudo[4365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:37 volumio dhcpcd[785]: wlan0: rebinding lease of 192.168.0.104 Jul 03 16:21:37 volumio sudo[4365]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:37 volumio dhcpcd[785]: wlan0: probing address 192.168.0.104/24 Jul 03 16:21:38 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jul 03 16:21:38 volumio volumio[3843]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jul 03 16:21:38 volumio volumio[3843]: info: Refreshing Cached IP Addresses Jul 03 16:21:38 volumio sudo[4368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jul 03 16:21:38 volumio sudo[4368]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:38 volumio sudo[4368]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:38 volumio systemd[1]: upmpdcli.service: Succeeded. Jul 03 16:21:38 volumio sudo[4371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 16:21:38 volumio sudo[4371]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:38 volumio sudo[4371]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:38 volumio wireless.js[4298]: trying... Jul 03 16:21:38 volumio sudo[4374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:21:38 volumio sudo[4374]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:38 volumio sudo[4374]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:38 volumio sudo[4377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:38 volumio sudo[4377]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:38 volumio sudo[4377]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:39 volumio wireless.js[4298]: trying... Jul 03 16:21:39 volumio sudo[4383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:39 volumio sudo[4383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:39 volumio sudo[4383]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:40 volumio wireless.js[4298]: trying... Jul 03 16:21:40 volumio sudo[4386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:40 volumio sudo[4386]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:40 volumio sudo[4386]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:40 volumio kernel: usb 3-4: device descriptor read/64, error -110 Jul 03 16:21:40 volumio kernel: usb usb3-port4: attempt power cycle Jul 03 16:21:40 volumio kernel: usb 3-4: new high-speed USB device number 45 using ehci-pci Jul 03 16:21:41 volumio wireless.js[4298]: trying... Jul 03 16:21:41 volumio sudo[4389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:41 volumio sudo[4389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:41 volumio sudo[4389]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:41 volumio volumio[3843]: info: MyVolumio login type: Token Jul 03 16:21:41 volumio volumio[3843]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 03 16:21:41 volumio volumio[3843]: info: Retrying Login Due to Network Error Jul 03 16:21:42 volumio wireless.js[4298]: trying... Jul 03 16:21:42 volumio sudo[4392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:42 volumio sudo[4392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:42 volumio sudo[4392]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:42 volumio dhcpcd[785]: wlan0: leased 192.168.0.104 for 7200 seconds Jul 03 16:21:42 volumio dhcpcd[785]: wlan0: adding route to 192.168.0.0/24 Jul 03 16:21:42 volumio dhcpcd[785]: wlan0: adding default route via 192.168.0.1 Jul 03 16:21:42 volumio avahi-daemon[732]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.104. Jul 03 16:21:42 volumio avahi-daemon[732]: New relevant interface wlan0.IPv4 for mDNS. Jul 03 16:21:42 volumio avahi-daemon[732]: Registering new address record for 192.168.0.104 on wlan0.IPv4. Jul 03 16:21:43 volumio ntpd[1618]: ntpd exiting on signal 15 (Terminated) Jul 03 16:21:43 volumio systemd[1]: Stopping Network Time Service... Jul 03 16:21:43 volumio systemd[1]: ntp.service: Succeeded. Jul 03 16:21:43 volumio systemd[1]: Stopped Network Time Service. Jul 03 16:21:43 volumio systemd[1]: Starting Network Time Service... Jul 03 16:21:43 volumio ntpd[4426]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 03 16:21:43 volumio ntpd[4426]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 03 16:21:43 volumio systemd[1]: Started Network Time Service. Jul 03 16:21:43 volumio ntpd[4432]: proto: precision = 0.328 usec (-21) Jul 03 16:21:43 volumio ntpd[4432]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 03 16:21:43 volumio ntpd[4432]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 03 16:21:43 volumio ntpd[4432]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 919 days ago Jul 03 16:21:43 volumio ntpd[4432]: Listen and drop on 0 v6wildcard [::]:123 Jul 03 16:21:43 volumio ntpd[4432]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 03 16:21:43 volumio ntpd[4432]: Listen normally on 2 lo 127.0.0.1:123 Jul 03 16:21:43 volumio ntpd[4432]: Listen normally on 3 wlan0 192.168.0.104:123 Jul 03 16:21:43 volumio ntpd[4432]: Listening on routing socket on fd #20 for interface updates Jul 03 16:21:43 volumio ntpd[4432]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 03 16:21:43 volumio ntpd[4432]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 03 16:21:43 volumio wireless.js[4298]: trying... Jul 03 16:21:43 volumio sudo[4435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 03 16:21:43 volumio sudo[4435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:43 volumio sudo[4435]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:43 volumio wireless.js[4298]: Connected to: ----MERCURY_8FD3 Jul 03 16:21:43 volumio wireless.js[4298]: ---- Jul 03 16:21:43 volumio sudo[4438]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:21:43 volumio sudo[4438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 16:21:43 volumio sudo[4438]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:43 volumio wireless.js[4298]: ... joined AP, wlan0 IPv4 is 192.168.0.104, ipV6 is undefined Jul 03 16:21:43 volumio wireless.js[4298]: It's done! AP Jul 03 16:21:43 volumio systemd[1]: Started Wireless Services. Jul 03 16:21:43 volumio sudo[4274]: pam_unix(sudo:session): session closed for user root Jul 03 16:21:43 volumio volumio[3843]: info: Discovery: adding 492524ff-0d18-4e9b-b0b7-e00a3046faf7 Jul 03 16:21:43 volumio volumio[3843]: info: Discovery: Found device Volumio Jul 03 16:21:43 volumio volumio[3843]: info: CoreCommandRouter::volumioGetState Jul 03 16:21:43 volumio volumio[3843]: info: CorePlayQueue::getTrack 0 Jul 03 16:21:43 volumio volumio[3843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 16:21:43 volumio volumio[3843]: Error: The value NaN is not a number Jul 03 16:21:43 volumio volumio[3843]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jul 03 16:21:43 volumio volumio[3843]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jul 03 16:21:43 volumio volumio[3843]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jul 03 16:21:43 volumio volumio[3843]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jul 03 16:21:43 volumio volumio[3843]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jul 03 16:21:43 volumio volumio[3843]: at Browser.emit (events.js:400:28) Jul 03 16:21:43 volumio volumio[3843]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 03 16:21:43 volumio volumio[3843]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 03 16:21:43 volumio volumio[3843]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jul 03 16:21:43 volumio volumio[3843]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jul 03 16:21:43 volumio volumio[3843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 16:21:44 volumio ntpd[4432]: Soliciting pool server 193.182.111.141 Jul 03 16:21:44 volumio sudo[4453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 16:20 Jul 03 16:21:44 volumio sudo[4453]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"