-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Wed 2024-05-08 22:47:51 EEST. -- May 08 22:46:04 volumiomx volumio[3051]: info: Discovery: Remote connection error: 10.18.20.10 May 08 22:46:12 volumiomx volumio[3051]: info: Discovery: Remote connection error: 10.18.20.10 May 08 22:46:18 volumiomx sudo[9597]: volumio : unable to resolve host volumiomx May 08 22:46:18 volumiomx sudo[9597]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:18 volumiomx sudo[9597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 22:46:18 volumiomx sudo[9597]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:18 volumiomx sudo[9597]: pam_unix(sudo:session): session closed for user root May 08 22:46:19 volumiomx sudo[9599]: volumio : unable to resolve host volumiomx May 08 22:46:19 volumiomx sudo[9599]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:19 volumiomx sudo[9599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 22:46:19 volumiomx sudo[9599]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:19 volumiomx sudo[9599]: pam_unix(sudo:session): session closed for user root May 08 22:46:19 volumiomx volumio[3051]: verbose: New Socket.io Connection to 10.18.20.8 from 10.18.20.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 9 May 08 22:46:19 volumiomx sudo[9603]: volumio : unable to resolve host volumiomx May 08 22:46:19 volumiomx sudo[9603]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:19 volumiomx sudo[9603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 22:46:19 volumiomx sudo[9603]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:19 volumiomx sudo[9603]: pam_unix(sudo:session): session closed for user root May 08 22:46:19 volumiomx sudo[9605]: volumio : unable to resolve host volumiomx May 08 22:46:19 volumiomx sudo[9605]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:19 volumiomx sudo[9605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 22:46:19 volumiomx sudo[9605]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:19 volumiomx sudo[9605]: pam_unix(sudo:session): session closed for user root May 08 22:46:19 volumiomx volumio[3051]: verbose: New Socket.io Connection to 10.18.20.8 from 10.18.20.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 10 May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 08 22:46:20 volumiomx volumio[3051]: info: Discovery: Remote connection error: 10.18.20.10 May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetVisibleSources May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetState May 08 22:46:20 volumiomx volumio[3051]: info: CorePlayQueue::getTrack 89 May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 22:46:20 volumiomx volumio[3051]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 08 22:46:20 volumiomx volumio[3051]: info: Received Get System Info May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 22:46:20 volumiomx volumio[3051]: info: Discovery: Getting this device information May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetState May 08 22:46:20 volumiomx volumio[3051]: info: CorePlayQueue::getTrack 89 May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetState May 08 22:46:20 volumiomx volumio[3051]: info: CorePlayQueue::getTrack 89 May 08 22:46:20 volumiomx volumio[3051]: info: Listing playlists May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 22:46:20 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 08 22:46:21 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetState May 08 22:46:21 volumiomx volumio[3051]: info: CorePlayQueue::getTrack 89 May 08 22:46:21 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 22:46:21 volumiomx volumio[3051]: info: Received Get System Info May 08 22:46:21 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 22:46:21 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 22:46:21 volumiomx volumio[3051]: info: Discovery: Getting this device information May 08 22:46:21 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetState May 08 22:46:21 volumiomx volumio[3051]: info: CorePlayQueue::getTrack 89 May 08 22:46:21 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 22:46:21 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 08 22:46:23 volumiomx volumio[3051]: info: Executing endpoint metavolumio May 08 22:46:23 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:46:23 volumiomx volumio[3051]: info: Executing endpoint metavolumio May 08 22:46:23 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:46:23 volumiomx volumio[3051]: info: Executing endpoint metavolumio May 08 22:46:23 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 22:46:25 volumiomx volumio[3051]: info: Received Get System Info May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 22:46:25 volumiomx volumio[3051]: info: Discovery: Getting this device information May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetState May 08 22:46:25 volumiomx volumio[3051]: info: CorePlayQueue::getTrack 89 May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 22:46:25 volumiomx volumio[3051]: info: Received Get System Info May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 22:46:25 volumiomx volumio[3051]: info: Discovery: Getting this device information May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetState May 08 22:46:25 volumiomx volumio[3051]: info: CorePlayQueue::getTrack 89 May 08 22:46:25 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 22:46:28 volumiomx volumio[3051]: info: Discovery: Remote connection error: 10.18.20.10 May 08 22:46:30 volumiomx volumio[3051]: error: Failed request for metavolumio API May 08 22:46:31 volumiomx volumio[3051]: info: [ASDebug] Port: 24 May 08 22:46:31 volumiomx volumio[3051]: info: [ASDebug] Inverted: false May 08 22:46:31 volumiomx volumio[3051]: info: [ASDebug] Delay: 720 May 08 22:46:36 volumiomx volumio[3051]: info: Discovery: Remote connection error: 10.18.20.10 May 08 22:46:38 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 22:46:38 volumiomx volumio[3051]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 08 22:46:38 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 08 22:46:38 volumiomx volumio[3051]: info: Received Get System Version May 08 22:46:38 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 22:46:38 volumiomx volumio[3051]: info: Received Get System Info May 08 22:46:38 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 22:46:38 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 22:46:38 volumiomx volumio[3051]: info: Discovery: Getting this device information May 08 22:46:38 volumiomx volumio[3051]: info: CoreCommandRouter::volumioGetState May 08 22:46:38 volumiomx volumio[3051]: info: CorePlayQueue::getTrack 89 May 08 22:46:38 volumiomx volumio[3051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 22:46:39 volumiomx volumio[3051]: info: Starting Uninstall of plugin system_controller - ampswitch May 08 22:46:39 volumiomx volumio[3051]: info: Uninstalling plugin ampswitch May 08 22:46:39 volumiomx volumio[3051]: info: [ASDebug] Port: 24 May 08 22:46:39 volumiomx volumio[3051]: info: [ASDebug] Inverted: false May 08 22:46:39 volumiomx volumio[3051]: info: [ASDebug] Delay: 720 May 08 22:46:39 volumiomx volumio[3051]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 22:46:39 volumiomx volumio[3051]: Error: EBADF: bad file descriptor, close May 08 22:46:39 volumiomx volumio[3051]: at Object.closeSync (fs.js:439:3) May 08 22:46:39 volumiomx volumio[3051]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) May 08 22:46:39 volumiomx volumio[3051]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) May 08 22:46:39 volumiomx volumio[3051]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:234:19) May 08 22:46:39 volumiomx volumio[3051]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:84:10) May 08 22:46:39 volumiomx volumio[3051]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 08 22:46:39 volumiomx volumio[3051]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) May 08 22:46:39 volumiomx volumio[3051]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1459:22) May 08 22:46:39 volumiomx volumio[3051]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1218:45) May 08 22:46:39 volumiomx volumio[3051]: at Socket.emit (events.js:315:20) May 08 22:46:39 volumiomx volumio[3051]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 08 22:46:39 volumiomx volumio[3051]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 08 22:46:39 volumiomx volumio[3051]: errno: -9, May 08 22:46:39 volumiomx volumio[3051]: syscall: 'close', May 08 22:46:39 volumiomx volumio[3051]: code: 'EBADF' May 08 22:46:39 volumiomx volumio[3051]: } May 08 22:46:39 volumiomx volumio[3051]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 22:46:39 volumiomx sudo[9664]: volumio : unable to resolve host volumiomx May 08 22:46:39 volumiomx sudo[9664]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:39 volumiomx sudo[9664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 22:45 May 08 22:46:39 volumiomx sudo[9664]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:39 volumiomx sudo[9664]: pam_unix(sudo:session): session closed for user root May 08 22:46:40 volumiomx volumio[3051]: sudo: unable to resolve host volumiomx: System error May 08 22:46:40 volumiomx volumio-remote-updater[732]: [2024-05-08 22:46:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 08 22:46:40 volumiomx volumio-remote-updater[732]: [2024-05-08 22:46:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 08 22:46:40 volumiomx vtcs[3516]: [2024-05-08 22:46:40.243] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 08 22:46:40 volumiomx go-librespot[3298]: time="2024-05-08T22:46:40+03:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 08 22:46:40 volumiomx systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 08 22:46:40 volumiomx systemd[1]: volumio.service: Failed with result 'exit-code'. May 08 22:46:40 volumiomx systemd[1]: Started dynamicswap service. May 08 22:46:40 volumiomx systemd[1]: dynamicswap.service: Succeeded. May 08 22:46:40 volumiomx systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 08 22:46:40 volumiomx systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. May 08 22:46:40 volumiomx systemd[1]: Started dynamicswap service. May 08 22:46:40 volumiomx systemd[1]: Stopped Volumio Backend Module. May 08 22:46:40 volumiomx systemd[1]: Started Volumio Backend Module. May 08 22:46:40 volumiomx systemd[1]: dynamicswap.service: Succeeded. May 08 22:46:41 volumiomx volumio[9679]: info: ------------------------------------------- May 08 22:46:41 volumiomx volumio[9679]: info: ----- Volumio3 ---- May 08 22:46:41 volumiomx volumio[9679]: info: ------------------------------------------- May 08 22:46:41 volumiomx volumio[9679]: info: ----- System startup ---- May 08 22:46:41 volumiomx volumio[9679]: info: ------------------------------------------- May 08 22:46:41 volumiomx volumio[9679]: info: MYVOLUMIO Environment detected May 08 22:46:42 volumiomx volumio[9679]: info: Plugin folders cleanup May 08 22:46:42 volumiomx volumio[9679]: info: Scanning into folder /volumio/app/plugins/ May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category audio_interface May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category miscellanea May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category music_service May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category plugins.json May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category system_controller May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category user_interface May 08 22:46:42 volumiomx volumio[9679]: info: Scanning into folder /data/plugins/ May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category music_service May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category system_controller May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category system_hardware May 08 22:46:42 volumiomx volumio[9679]: info: Scanning category user_interface May 08 22:46:42 volumiomx volumio[9679]: info: Plugin folders cleanup completed May 08 22:46:42 volumiomx volumio[9679]: info: ------------------------------------------- May 08 22:46:42 volumiomx volumio[9679]: info: ----- Core plugins startup ---- May 08 22:46:42 volumiomx volumio[9679]: info: ------------------------------------------- May 08 22:46:42 volumiomx volumio[9679]: info: Loading plugins from folder /volumio/app/plugins/ May 08 22:46:42 volumiomx volumio[9679]: info: Adding plugin upnp to MyMusic Plugins May 08 22:46:42 volumiomx volumio[9679]: info: Adding plugin airplay_emulation to MyMusic Plugins May 08 22:46:42 volumiomx volumio[9679]: info: Adding plugin upnp_browser to MyMusic Plugins May 08 22:46:42 volumiomx volumio[9679]: info: Loading plugins from folder /data/plugins/ May 08 22:46:42 volumiomx volumio[9679]: info: Loading plugin "system"... May 08 22:46:42 volumiomx volumio[9679]: info: Loading plugin "ampswitch"... May 08 22:46:42 volumiomx volumio[9679]: info: Applying required configuration parameters for plugin ampswitch May 08 22:46:42 volumiomx volumio[9679]: info: Loading plugin "appearance"... May 08 22:46:42 volumiomx volumio[9679]: info: Loading plugin "network"... May 08 22:46:42 volumiomx volumio[9679]: info: Refreshing Cached IP Addresses May 08 22:46:42 volumiomx volumio[9679]: info: Loading plugin "services"... May 08 22:46:42 volumiomx volumio[9679]: info: Loading plugin "alsa_controller"... May 08 22:46:42 volumiomx sudo[9708]: volumio : unable to resolve host volumiomx May 08 22:46:42 volumiomx sudo[9708]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:42 volumiomx sudo[9708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 22:46:42 volumiomx sudo[9708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:42 volumiomx sudo[9708]: pam_unix(sudo:session): session closed for user root May 08 22:46:42 volumiomx sudo[9710]: volumio : unable to resolve host volumiomx May 08 22:46:42 volumiomx sudo[9710]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:42 volumiomx sudo[9710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 22:46:42 volumiomx sudo[9710]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:42 volumiomx sudo[9710]: pam_unix(sudo:session): session closed for user root May 08 22:46:42 volumiomx sudo[9712]: volumio : unable to resolve host volumiomx May 08 22:46:42 volumiomx sudo[9712]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:43 volumiomx sudo[9712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 08 22:46:43 volumiomx sudo[9712]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:43 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "wizard"... May 08 22:46:43 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:43 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:43 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 May 08 22:46:43 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "networkfs"... May 08 22:46:43 volumiomx volumio[9679]: info: Cannot mount NAS NAs at system boot, trial number 1 ,retrying in 5 seconds May 08 22:46:43 volumiomx volumio[9679]: info: Starting Udev Watcher for removable devices May 08 22:46:43 volumiomx volumio[9679]: info: Ignoring mount for partition: boot May 08 22:46:43 volumiomx volumio[9679]: info: Ignoring mount for partition: volumio May 08 22:46:43 volumiomx volumio[9679]: info: Ignoring mount for partition: volumio_data May 08 22:46:43 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "volumio_command_line_client"... May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "upnp"... May 08 22:46:43 volumiomx volumio[9679]: info: [1715197603074] Starting Upmpd Daemon May 08 22:46:43 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "my_music"... May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "mpd"... May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "upnp_browser"... May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "alarm-clock"... May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "airplay_emulation"... May 08 22:46:43 volumiomx volumio[9679]: info: Starting Shairport Sync May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "last_100"... May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "webradio"... May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "i2s_dacs"... May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "volumiodiscovery"... May 08 22:46:43 volumiomx volumio[9679]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 08 22:46:43 volumiomx volumio[9679]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 22:46:43 volumiomx node[9679]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 08 22:46:43 volumiomx volumio[9679]: *** WARNING *** For more information see May 08 22:46:43 volumiomx volumio[9679]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 08 22:46:43 volumiomx volumio[9679]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 22:46:43 volumiomx volumio[9679]: *** WARNING *** For more information see May 08 22:46:43 volumiomx node[9679]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 22:46:43 volumiomx node[9679]: *** WARNING *** For more information see May 08 22:46:43 volumiomx node[9679]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 08 22:46:43 volumiomx node[9679]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 22:46:43 volumiomx node[9679]: *** WARNING *** For more information see May 08 22:46:43 volumiomx volumio[9679]: info: Applying required configuration parameters for plugin volumiodiscovery May 08 22:46:43 volumiomx volumio[9679]: info: Discovery: Started advertising with name: Volumio_mx May 08 22:46:43 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 22:46:43 volumiomx volumio[9679]: info: Loading plugin "spop"... May 08 22:46:44 volumiomx volumio[9679]: info: Loading plugin "now_playing"... May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "outputs"... May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "albumart"... May 08 22:46:45 volumiomx volumio[9679]: info: Plugin example_plugin is not enabled May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "inputs"... May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "updater_comm"... May 08 22:46:45 volumiomx volumio[9679]: info: Plugin mpdemulation is not enabled May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "rest_api"... May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "websocket"... May 08 22:46:45 volumiomx volumio-remote-updater[732]: [2024-05-08 22:46:45] [connect] Successful connection May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "audiophonics_evo_sabre"... May 08 22:46:45 volumiomx volumio[9679]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "Systeminfo"... May 08 22:46:45 volumiomx volumio[9679]: Forking 3 albumart workers May 08 22:46:45 volumiomx volumio[9679]: info: Loading plugin "touch_display"... May 08 22:46:45 volumiomx volumio[9679]: info: Applying required configuration parameters for plugin touch_display May 08 22:46:45 volumiomx volumio[9679]: info: Loading i18n strings for locale ru May 08 22:46:45 volumiomx volumio[9679]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 08 22:46:45 volumiomx volumio[9679]: Updating browse sources language May 08 22:46:45 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::initPlayerControls May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: Express server listening on port 3000 May 08 22:46:46 volumiomx volumio[9679]: [Metrics] WebUI: 4s 869.02ms May 08 22:46:46 volumiomx volumio[9679]: Starting albumart workers May 08 22:46:46 volumiomx volumio[9679]: Starting albumart workers May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::resetVolumioState May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::getcurrentVolume May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioRetrievevolume May 08 22:46:46 volumiomx volumio[9679]: Starting albumart workers May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 May 08 22:46:46 volumiomx volumio[9679]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 1 May 08 22:46:46 volumiomx volumio[9679]: verbose: New Socket.io Connection to 10.18.20.8 from 10.18.20.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 2 May 08 22:46:46 volumiomx volumio[9679]: verbose: New Socket.io Connection to 10.18.20.8 from 10.18.20.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3 May 08 22:46:46 volumiomx volumio-remote-updater[732]: [2024-05-08 22:46:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715197605 101 May 08 22:46:46 volumiomx volumio[9679]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 May 08 22:46:46 volumiomx volumio[9679]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 May 08 22:46:46 volumiomx volumio[9679]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:46:46 volumiomx volumio[9679]: info: Reloading queue from file May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::setRepeat null single undefined May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::pushState May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPushState May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::setRandom true May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::pushState May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPushState May 08 22:46:46 volumiomx volumio[9679]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 08 22:46:46 volumiomx volumio[9679]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 May 08 22:46:46 volumiomx volumio[9679]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 08 22:46:46 volumiomx volumio[9679]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 08 22:46:46 volumiomx volumio[9679]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined May 08 22:46:46 volumiomx volumio[9679]: info: VolumeController:: Volume=100 Mute =true May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::pushState May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPushState May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::updateTrackBlock May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getTrackBlock May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioRetrievevolume May 08 22:46:46 volumiomx volumio[9679]: info: Setting Device type: Raspberry PI May 08 22:46:46 volumiomx volumio[9679]: info: Completed loading Core Plugins May 08 22:46:46 volumiomx volumio[9679]: info: Preparing to generate the ALSA configuration file May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 May 08 22:46:46 volumiomx volumio[9679]: info: Listing playlists May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetQueue May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::getQueue May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getQueue May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetVisibleSources May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:46 volumiomx volumio[9679]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 08 22:46:46 volumiomx volumio[9679]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined May 08 22:46:46 volumiomx volumio[9679]: info: Listing playlists May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetQueue May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::getQueue May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getQueue May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetVisibleSources May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:46 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 May 08 22:46:46 volumiomx volumio[9679]: info: VolumeController:: Volume=100 Mute =true May 08 22:46:46 volumiomx volumio[9679]: info: CoreStateMachine::pushState May 08 22:46:46 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:46 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPushState May 08 22:46:47 volumiomx volumio[9679]: info: Asound.conf file written May 08 22:46:47 volumiomx sudo[9712]: pam_unix(sudo:session): session closed for user root May 08 22:46:47 volumiomx sudo[9788]: volumio : unable to resolve host volumiomx May 08 22:46:47 volumiomx sudo[9788]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:47 volumiomx sudo[9788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 08 22:46:47 volumiomx sudo[9788]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:47 volumiomx sudo[9788]: pam_unix(sudo:session): session closed for user root May 08 22:46:47 volumiomx volumio[9679]: sudo: unable to resolve host volumiomx: System error May 08 22:46:47 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:46:47 volumiomx volumio[9679]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error May 08 22:46:47 volumiomx volumio[9679]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument May 08 22:46:47 volumiomx volumio[9679]: info: Output device has changed, restarting MPD May 08 22:46:47 volumiomx volumio[9679]: info: Output device has changed, restarting Shairport Sync May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:46:47 volumiomx sudo[9794]: volumio : unable to resolve host volumiomx May 08 22:46:47 volumiomx sudo[9794]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:47 volumiomx sudo[9794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 22:46:47 volumiomx sudo[9794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:47 volumiomx sudo[9794]: pam_unix(sudo:session): session closed for user root May 08 22:46:47 volumiomx volumio[9679]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 22:46:47 volumiomx volumio[9679]: info: ___________ START PLUGINS ___________ May 08 22:46:47 volumiomx sudo[9797]: volumio : unable to resolve host volumiomx May 08 22:46:47 volumiomx sudo[9797]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:47 volumiomx sudo[9797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 22:46:47 volumiomx sudo[9797]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:47 volumiomx volumio[9679]: info: ControllerMpd::onStart: Initializing MPD May 08 22:46:47 volumiomx volumio[9679]: info: Creating MPD Configuration file May 08 22:46:47 volumiomx systemd[1]: Stopping Music Player Daemon... May 08 22:46:47 volumiomx systemd[1]: mpd.service: Succeeded. May 08 22:46:47 volumiomx systemd[1]: Stopped Music Player Daemon. May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 22:46:47 volumiomx volumio[9679]: info: [1715197607424] CoreMusicLibrary::Adding element Медиа-серверы May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:46:47 volumiomx systemd[1]: Starting Music Player Daemon... May 08 22:46:47 volumiomx sudo[9811]: volumio : unable to resolve host volumiomx May 08 22:46:47 volumiomx sudo[9811]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:47 volumiomx sudo[9811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 22:46:47 volumiomx sudo[9811]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:47 volumiomx sudo[9813]: volumio : unable to resolve host volumiomx May 08 22:46:47 volumiomx sudo[9813]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:47 volumiomx sudo[9811]: pam_unix(sudo:session): session closed for user root May 08 22:46:47 volumiomx sudo[9813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 22:46:47 volumiomx sudo[9813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:47 volumiomx volumio[9679]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:46:47 volumiomx systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 08 22:46:47 volumiomx systemd[1]: mpd.service: Succeeded. May 08 22:46:47 volumiomx systemd[1]: Stopped Music Player Daemon. May 08 22:46:47 volumiomx systemd[1]: Starting Music Player Daemon... May 08 22:46:47 volumiomx volumio[9679]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 22:46:47 volumiomx volumio[9679]: info: [1715197607613] CoreMusicLibrary::Adding element Last_100 May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 22:46:47 volumiomx volumio[9679]: info: [1715197607615] CoreMusicLibrary::Adding element Webradio May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 22:46:47 volumiomx volumio[9679]: info: Initializing BBC Radios May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:46:47 volumiomx volumio[9679]: info: Creating Spotify config file May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:47 volumiomx volumio[9679]: info: [now-playing] ConfigUpdater: config is up to date. May 08 22:46:47 volumiomx volumio[9679]: info: EVO SABRE : Starting Plugin May 08 22:46:47 volumiomx volumio[9679]: info: Loading i18n strings for locale ru May 08 22:46:47 volumiomx volumio[9679]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 08 22:46:47 volumiomx volumio[9679]: info: Loading i18n strings for locale ru May 08 22:46:47 volumiomx volumio[9679]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 08 22:46:47 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 22:46:47 volumiomx volumio[9679]: info: Volumio Calling Home May 08 22:46:47 volumiomx sudo[9840]: volumio : unable to resolve host volumiomx May 08 22:46:47 volumiomx sudo[9840]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:48 volumiomx sudo[9840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 08 22:46:48 volumiomx sudo[9840]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:48 volumiomx volumio[9679]: info: [now-playing] Fetching API key... May 08 22:46:48 volumiomx volumio[9679]: info: [now-playing] App is listening on port 4004. May 08 22:46:48 volumiomx volumio[9679]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 08 22:46:48 volumiomx sudo[9842]: volumio : unable to resolve host volumiomx May 08 22:46:48 volumiomx sudo[9842]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:48 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:48 volumiomx sudo[9842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 08 22:46:48 volumiomx sudo[9842]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:48 volumiomx sudo[9844]: volumio : unable to resolve host volumiomx May 08 22:46:48 volumiomx sudo[9844]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:48 volumiomx volumio[9679]: info: Cannot mount NAS NAs at system boot, trial number 2 ,retrying in 5 seconds May 08 22:46:48 volumiomx volumio[9679]: info: Discovery: adding ab4546df-25b3-43cb-9d6e-e1e5b7a4039d May 08 22:46:48 volumiomx volumio[9679]: info: Discovery: Found device Volumio_mx May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:48 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:48 volumiomx volumio[9679]: info: touch_display: No backlight interface detected. May 08 22:46:48 volumiomx sudo[9840]: pam_unix(sudo:session): session closed for user root May 08 22:46:48 volumiomx sudo[9844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 08 22:46:48 volumiomx sudo[9844]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:48 volumiomx systemd[1]: Reloading. May 08 22:46:48 volumiomx volumio[9679]: info: MPD Permissions set May 08 22:46:48 volumiomx volumio[9679]: info: MPD Permissions set May 08 22:46:48 volumiomx sudo[9849]: volumio : unable to resolve host volumiomx May 08 22:46:48 volumiomx sudo[9849]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:48 volumiomx sudo[9849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 08 22:46:48 volumiomx sudo[9849]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:48 volumiomx volumio[9679]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 08 22:46:48 volumiomx volumio[9679]: info: Volumio called home May 08 22:46:48 volumiomx volumio[9679]: info: Spotify config file written May 08 22:46:48 volumiomx systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 08 22:46:48 volumiomx sudo[9865]: volumio : unable to resolve host volumiomx May 08 22:46:48 volumiomx sudo[9865]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:48 volumiomx sudo[9865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 08 22:46:48 volumiomx sudo[9865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:48 volumiomx volumio[9679]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx mpd[9821]: May 08 22:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:46:48 volumiomx volumio[9679]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 08 22:46:49 volumiomx systemd[1]: Started Music Player Daemon. May 08 22:46:49 volumiomx sudo[9813]: pam_unix(sudo:session): session closed for user root May 08 22:46:49 volumiomx sudo[9842]: pam_unix(sudo:session): session closed for user root May 08 22:46:49 volumiomx sudo[9797]: pam_unix(sudo:session): session closed for user root May 08 22:46:49 volumiomx volumio[9679]: info: Starting Shairport Sync May 08 22:46:49 volumiomx sudo[9878]: volumio : unable to resolve host volumiomx May 08 22:46:49 volumiomx sudo[9878]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:49 volumiomx sudo[9878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 08 22:46:49 volumiomx sudo[9878]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:49 volumiomx systemd[1]: Reloading. May 08 22:46:49 volumiomx sudo[9878]: pam_unix(sudo:session): session closed for user root May 08 22:46:49 volumiomx volumio[9679]: info: Starting Shairport Sync May 08 22:46:49 volumiomx volumio[9679]: info: Starting Shairport Sync May 08 22:46:49 volumiomx volumio[9679]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 08 22:46:49 volumiomx volumio[9679]: SPOTIFY: BQCVvDwek9_j_q-UmzRh3kgYZnQbgC7jM0rCLaMRXhasejuapHqR3-fg6L7S6h5-Q61ZU0tw9bVPOJ6oX4g_kKMkR8rjanA54hRX7SPnHHBkBzzzymr7-K5gQ64Rxgfwa-EQ_-G_PAPPVQPpvoQk3fqEe8HYvXtMtQJsYPwDHHqVAFaw_sUMu2bLFGfAG1m6AK-v8vPthlr-o-RrxiuPHlt5ipCOuXeeSVo3dYdCyjlsiIL0Vo1foqmSM6KeBrf-zSCE6W0 May 08 22:46:49 volumiomx volumio[9679]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 08 22:46:49 volumiomx volumio[9679]: info: New Spotify access token = BQCVvDwek9_j_q-UmzRh3kgYZnQbgC7jM0rCLaMRXhasejuapHqR3-fg6L7S6h5-Q61ZU0tw9bVPOJ6oX4g_kKMkR8rjanA54hRX7SPnHHBkBzzzymr7-K5gQ64Rxgfwa-EQ_-G_PAPPVQPpvoQk3fqEe8HYvXtMtQJsYPwDHHqVAFaw_sUMu2bLFGfAG1m6AK-v8vPthlr-o-RrxiuPHlt5ipCOuXeeSVo3dYdCyjlsiIL0Vo1foqmSM6KeBrf-zSCE6W0 May 08 22:46:49 volumiomx volumio[9679]: info: Spotify credentials grant success - running version from March 24, 2019 May 08 22:46:49 volumiomx volumio[9679]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 08 22:46:49 volumiomx sudo[9881]: volumio : unable to resolve host volumiomx May 08 22:46:49 volumiomx sudo[9881]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:49 volumiomx sudo[9881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 22:46:49 volumiomx sudo[9881]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:49 volumiomx sudo[9894]: volumio : unable to resolve host volumiomx May 08 22:46:49 volumiomx sudo[9894]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:49 volumiomx sudo[9883]: volumio : unable to resolve host volumiomx May 08 22:46:49 volumiomx sudo[9883]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:49 volumiomx sudo[9883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 22:46:49 volumiomx sudo[9883]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:49 volumiomx sudo[9894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 22:46:49 volumiomx sudo[9894]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:49 volumiomx volumio[9679]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 08 22:46:49 volumiomx systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 08 22:46:49 volumiomx volumio[9679]: error: MPD error: The expression evaluated to a falsy value: May 08 22:46:49 volumiomx volumio[9679]: assert.ok(self.idling) May 08 22:46:49 volumiomx volumio[9679]: error: The expression evaluated to a falsy value: May 08 22:46:49 volumiomx volumio[9679]: assert.ok(self.idling) May 08 22:46:49 volumiomx volumio[9679]: info: MPD running with PID9821 May 08 22:46:49 volumiomx volumio[9679]: ,establishing connection May 08 22:46:49 volumiomx volumio[9679]: error: updateQueue error: null May 08 22:46:49 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:49 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:49 volumiomx volumio[9679]: info: [ASDebug] CurState: stop PrevState: na May 08 22:46:49 volumiomx volumio[9679]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 08 22:46:49 volumiomx volumio[9679]: info: [ASDebug] CurState: stop PrevState: na May 08 22:46:49 volumiomx volumio[9679]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 08 22:46:49 volumiomx volumio[9679]: error: updateQueue error: null May 08 22:46:49 volumiomx volumio[9679]: SPOTIFY: User informations: {"display_name":"vatero","external_urls":{"spotify":"https://open.spotify.com/user/qu9tx3luo37dacc60h2igy194"},"href":"https://api.spotify.com/v1/users/qu9tx3luo37dacc60h2igy194","id":"qu9tx3luo37dacc60h2igy194","images":[],"type":"user","uri":"spotify:user:qu9tx3luo37dacc60h2igy194","followers":{"href":null,"total":2},"country":"UA","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"vold.erofeev@gmail.com"} May 08 22:46:49 volumiomx volumio[9679]: info: Spotify Successfully logged in May 08 22:46:49 volumiomx volumio[9679]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 22:46:49 volumiomx volumio[9679]: info: [1715197609606] CoreMusicLibrary::Adding element Spotify May 08 22:46:49 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:49 volumiomx volumio[9679]: Cannot find translation for source Spotify May 08 22:46:49 volumiomx sudo[9844]: pam_unix(sudo:session): session closed for user root May 08 22:46:49 volumiomx volumio[9679]: info: touch_display: systemctl daemon-reload succeeded. May 08 22:46:49 volumiomx systemd[1]: Reloading. May 08 22:46:49 volumiomx volumio[9679]: info: [now-playing] OpenWeatherMap API key obtained. May 08 22:46:49 volumiomx sudo[9905]: volumio : unable to resolve host volumiomx May 08 22:46:49 volumiomx sudo[9905]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:49 volumiomx sudo[9905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 08 22:46:49 volumiomx sudo[9905]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:50 volumiomx volumio[9679]: verbose: New Socket.io Connection to 10.18.20.8 from 10.18.20.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8 May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetVisibleSources May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 22:46:50 volumiomx volumio[9679]: info: Received Get System Info May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 22:46:50 volumiomx volumio[9679]: info: Discovery: Getting this device information May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:50 volumiomx volumio[9679]: info: Listing playlists May 08 22:46:50 volumiomx systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 08 22:46:50 volumiomx volumio[9679]: info: [ASDebug] Togle GPIO: OFF May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 22:46:50 volumiomx volumio[9679]: info: Discovery: Getting this device information May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 22:46:50 volumiomx sudo[9849]: pam_unix(sudo:session): session closed for user root May 08 22:46:50 volumiomx volumio[9679]: info: EVO SABRE : systemd cmd daemon-reload : success May 08 22:46:50 volumiomx sudo[9905]: pam_unix(sudo:session): session closed for user root May 08 22:46:50 volumiomx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 22:46:50 volumiomx systemd[1]: Stopping go-librespot Daemon... May 08 22:46:50 volumiomx systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 08 22:46:50 volumiomx systemd[1]: go-librespot-daemon.service: Succeeded. May 08 22:46:50 volumiomx volumio[9679]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 08 22:46:50 volumiomx systemd[1]: Stopped go-librespot Daemon. May 08 22:46:50 volumiomx volumio[9679]: info: touch_display: Volumio Kiosk started. May 08 22:46:50 volumiomx systemd[1]: shairport-sync.service: Succeeded. May 08 22:46:50 volumiomx systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 22:46:50 volumiomx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 22:46:50 volumiomx sudo[9894]: pam_unix(sudo:session): session closed for user root May 08 22:46:50 volumiomx systemd[1]: Started go-librespot Daemon. May 08 22:46:50 volumiomx go-librespot[9924]: Librespot-go daemon starting... May 08 22:46:50 volumiomx sudo[9865]: pam_unix(sudo:session): session closed for user root May 08 22:46:50 volumiomx sudo[9881]: pam_unix(sudo:session): session closed for user root May 08 22:46:50 volumiomx sudo[9883]: pam_unix(sudo:session): session closed for user root May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:50 volumiomx go-librespot[9924]: time="2024-05-08T22:46:50+03:00" level=info msg="generated new device id: 1007d8ff45a321b49f92d04b30b13e87f48553aa" May 08 22:46:50 volumiomx go-librespot[9924]: time="2024-05-08T22:46:50+03:00" level=debug msg="stored credentials found for qu9tx3luo37dacc60h2igy194" May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:50 volumiomx volumio[9679]: info: Shairport-Sync Started May 08 22:46:50 volumiomx volumio[9679]: Error adding Membership: Error: addMembership EINVAL May 08 22:46:50 volumiomx volumio[9679]: info: Shairport-Sync Started May 08 22:46:50 volumiomx volumio[9679]: info: Shairport-Sync Started May 08 22:46:50 volumiomx sudo[9921]: volumio : unable to resolve host volumiomx May 08 22:46:50 volumiomx sudo[9921]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:50 volumiomx sudo[9921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service May 08 22:46:50 volumiomx sudo[9921]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:50 volumiomx volumio[9679]: info: touch_display: X display number found: 0 May 08 22:46:50 volumiomx systemd[1]: Started OLED Display Service for EVO SABRE. May 08 22:46:50 volumiomx sudo[9921]: pam_unix(sudo:session): session closed for user root May 08 22:46:50 volumiomx volumio[9679]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 08 22:46:50 volumiomx volumio[9679]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success May 08 22:46:50 volumiomx volumio[9679]: info: touch_display: X display number found: 0 May 08 22:46:50 volumiomx sudo[9935]: root : unable to resolve host volumiomx May 08 22:46:50 volumiomx sudo[9935]: root : problem with defaults entries ; TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; May 08 22:46:50 volumiomx sudo[9935]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio May 08 22:46:50 volumiomx sudo[9935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:46:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:46:50 volumiomx go-librespot[9924]: time="2024-05-08T22:46:50+03:00" level=debug msg="obtained new client token: AAAyyA2SmvcivZy8cHS+1LN54N37FBfxoRTbynDXUy+PJt/pX0OdPJOsEt8w9aNLr0Y12UMpT02ticOh9mvAo88gcGDYmgVnKKREEhvPCRrKFCXaFa/hQb0eiNHtwYt6BJ0fT4bB6S2pnLB8FFCmvt0EkQ717VJ2BN2jjqERSjeU4uxPyJClo0gtKu5PbdrzHuqxa7zZe5gvHYwKcoy6T7FRKUkGKJOsxATqRRHhfCj790KmIhq1ZyhdXA9ffwQCgg==" May 08 22:46:50 volumiomx sudo[9940]: volumio : unable to resolve host volumiomx May 08 22:46:50 volumiomx sudo[9940]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:50 volumiomx sudo[9940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service May 08 22:46:50 volumiomx sudo[9940]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:50 volumiomx volumio[9679]: info: touch_display: Setting screensaver timeout to 0 seconds. May 08 22:46:50 volumiomx lircd[3337]: lircd-0.10.1[3337]: Notice: caught signal May 08 22:46:50 volumiomx lircd-0.10.1[3337]: Notice: caught signal May 08 22:46:50 volumiomx irexec[3348]: {"time":1715190407604,"response":"toggle Success"} May 08 22:46:50 volumiomx systemd[1]: Stopping evo_remote.service... May 08 22:46:50 volumiomx systemd[1]: evo_remote.service: Main process exited, code=killed, status=15/TERM May 08 22:46:50 volumiomx systemd[1]: evo_remote.service: Succeeded. May 08 22:46:50 volumiomx systemd[1]: Stopped evo_remote.service. May 08 22:46:50 volumiomx systemd[1]: evo_irexec.service: Succeeded. May 08 22:46:51 volumiomx systemd[1]: Starting lircd(8) initialization helper tool... May 08 22:46:51 volumiomx go-librespot[9924]: time="2024-05-08T22:46:51+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 22:46:51 volumiomx lircd-setup[9955]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 08 22:46:51 volumiomx lircd-setup[9955]: parser = configparser.SafeConfigParser() May 08 22:46:51 volumiomx systemd[1]: lircd-setup.service: Succeeded. May 08 22:46:51 volumiomx systemd[1]: Started lircd(8) initialization helper tool. May 08 22:46:51 volumiomx systemd[1]: Started evo_remote.service. May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Info: lircd: Opening log, level: Info May 08 22:46:51 volumiomx go-librespot[9924]: time="2024-05-08T22:46:51+03:00" level=debug msg="completed keyexchange" May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Version: lircd 0.10.1 May 08 22:46:51 volumiomx sudo[9940]: pam_unix(sudo:session): session closed for user root May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: System info: Linux volumiomx 6.1.77-v7l+ #1730 SMP Thu Feb 8 15:22:02 GMT 2024 armv7l GNU/Linux May 08 22:46:51 volumiomx volumio[9679]: info: EVO SABRE : systemd cmd restart evo_remote.service : success May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Info: Initial device: /dev/lirc0 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Info: [lirc] protocol is enabled May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Info: Initial device: /dev/lirc0 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Info: [lirc] protocol is enabled May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: driver: default May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: output: /var/run/lirc/lircd May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: nodaemon: 1 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: logfile: syslog May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: immediate-init: 0 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: permission: 666 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: driver-options: May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: listen: 0 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: connect: (null) May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: userelease: 0 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: effective_user: (null) May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: release_suffix: _EVUP May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: allow_simulate: 0 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: repeat_max: 600 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Options: dynamic_codes: (null) May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Current driver: default May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Driver API version: 3 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Driver version: 0.10.0 May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 08 22:46:51 volumiomx lircd[9963]: lircd-0.10.1[9963]: Info: lircd: Opening log, level: Info May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Info: lircd: Opening log, level: Info May 08 22:46:51 volumiomx lircd[9963]: lircd-0.10.1[9963]: Warning: Running as root May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Warning: Running as root May 08 22:46:51 volumiomx lircd[9963]: lircd-0.10.1[9963]: Info: Using remote: ApEvo. May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Info: Using remote: ApEvo. May 08 22:46:51 volumiomx lircd[9963]: lircd-0.10.1[9963]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 08 22:46:51 volumiomx volumio[9679]: info: Discovery: Browse raised the following error Error: dns service error: unknown May 08 22:46:51 volumiomx sudo[9967]: volumio : unable to resolve host volumiomx May 08 22:46:51 volumiomx sudo[9967]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:51 volumiomx sudo[9967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service May 08 22:46:51 volumiomx sudo[9967]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:51 volumiomx sudo[9935]: pam_unix(sudo:session): session closed for user root May 08 22:46:51 volumiomx systemd[1]: Starting lircd(8) initialization helper tool... May 08 22:46:51 volumiomx systemd[1]: evo_oled2.service: Main process exited, code=exited, status=1/FAILURE May 08 22:46:51 volumiomx systemd[1]: evo_oled2.service: Failed with result 'exit-code'. May 08 22:46:51 volumiomx lircd-setup[9973]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 08 22:46:51 volumiomx lircd-setup[9973]: parser = configparser.SafeConfigParser() May 08 22:46:51 volumiomx systemd[1]: lircd-setup.service: Succeeded. May 08 22:46:51 volumiomx systemd[1]: Started lircd(8) initialization helper tool. May 08 22:46:51 volumiomx systemd[1]: Started evo_irexec.service. May 08 22:46:51 volumiomx sudo[9967]: pam_unix(sudo:session): session closed for user root May 08 22:46:51 volumiomx volumio[9679]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success May 08 22:46:51 volumiomx lircd[9963]: lircd-0.10.1[9963]: Notice: accepted new client on /var/run/lirc/lircd May 08 22:46:51 volumiomx lircd[9963]: lircd-0.10.1[9963]: Info: [lirc] protocol is enabled May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Notice: accepted new client on /var/run/lirc/lircd May 08 22:46:51 volumiomx volumio[9679]: info: Completed starting Core Plugins May 08 22:46:51 volumiomx lircd-0.10.1[9963]: Info: [lirc] protocol is enabled May 08 22:46:51 volumiomx volumio[9679]: info: ------------------------------------------- May 08 22:46:51 volumiomx volumio[9679]: info: ----- MyVolumio plugins startup ---- May 08 22:46:51 volumiomx volumio[9679]: info: ------------------------------------------- May 08 22:46:51 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Fetching plans data.... May 08 22:46:51 volumiomx go-librespot[9924]: time="2024-05-08T22:46:51+03:00" level=debug msg="completed challenge" May 08 22:46:51 volumiomx go-librespot[9924]: time="2024-05-08T22:46:51+03:00" level=debug msg="authenticated as qu9tx3luo37dacc60h2igy194" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="authenticated as qu9tx3luo37dacc60h2igy194" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="dealer connection opened" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="received connection id: MjM5ZTVmZTUtMzc2YS00NzE5LTk2NTktMWQxZDI0YmFhOGRjK2RlYWxlcit0Y3A6Ly9nZXcxLWRlYWxlci1hLWd6NDUuZ2V3MS5zcG90aWZ5Lm5ldDo1NzAwKzJFRjkwQTgwQjI3OTJDMkMxQTkzQjZBNUM4RTQ4QkRGODU2MDlBRUZEOTkyNDdFMUU4QUEyRTREREE5QzMwNkU=" May 08 22:46:52 volumiomx go-librespot[9924]: time="2024-05-08T22:46:52+03:00" level=debug msg="put connect state because NEW_DEVICE" May 08 22:46:53 volumiomx volumio[9679]: info: Cannot mount NAS NAs at system boot, trial number 3 ,retrying in 5 seconds May 08 22:46:53 volumiomx sudo[9976]: volumio : unable to resolve host volumiomx May 08 22:46:53 volumiomx sudo[9976]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:53 volumiomx sudo[9976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 22:46:53 volumiomx sudo[9976]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:53 volumiomx sudo[9976]: pam_unix(sudo:session): session closed for user root May 08 22:46:53 volumiomx sudo[9978]: volumio : unable to resolve host volumiomx May 08 22:46:53 volumiomx sudo[9978]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:53 volumiomx sudo[9978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 22:46:53 volumiomx sudo[9978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:53 volumiomx sudo[9978]: pam_unix(sudo:session): session closed for user root May 08 22:46:53 volumiomx sudo[9980]: volumio : unable to resolve host volumiomx May 08 22:46:53 volumiomx sudo[9980]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:46:53 volumiomx sudo[9980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 08 22:46:53 volumiomx sudo[9980]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:46:53 volumiomx sudo[9980]: pam_unix(sudo:session): session closed for user root May 08 22:46:53 volumiomx volumio[9679]: info: Upmpdcli Daemon Started May 08 22:46:53 volumiomx volumio[9679]: info: go-librespot daemon successfully initialized May 08 22:46:54 volumiomx volumio[9679]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 08 22:46:55 volumiomx volumio[9679]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 22:46:55 volumiomx volumio[9679]: info: [ASDebug] Setting UI defaults May 08 22:46:55 volumiomx volumio[9679]: info: [ASDebug] Port: 24 May 08 22:46:55 volumiomx volumio[9679]: info: [ASDebug] Inverted: false May 08 22:46:55 volumiomx volumio[9679]: info: [ASDebug] Latched: false May 08 22:46:55 volumiomx volumio[9679]: info: [ASDebug] On pulse width: 500 May 08 22:46:55 volumiomx volumio[9679]: info: [ASDebug] Off pulse width: 500 May 08 22:46:57 volumiomx volumio[9679]: info: Initializing connection to go-librespot Websocket May 08 22:46:57 volumiomx go-librespot[9924]: time="2024-05-08T22:46:57+03:00" level=debug msg="new websocket client" May 08 22:46:57 volumiomx volumio[9679]: info: Connection to go-librespot Websocket established May 08 22:46:58 volumiomx volumio[9679]: info: Cannot mount NAS NAs at system boot, trial number 4 ,retrying in 5 seconds May 08 22:47:00 volumiomx volumio[9679]: info: Getting Spotify volume May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 08 22:47:00 volumiomx volumio[9679]: info: Adding plugin bluetooth to MyMusic Plugins May 08 22:47:00 volumiomx volumio[9679]: info: Adding plugin multiroom to MyMusic Plugins May 08 22:47:00 volumiomx volumio[9679]: info: Adding plugin metavolumio to MyMusic Plugins May 08 22:47:00 volumiomx volumio[9679]: info: Adding plugin cd_controller to MyMusic Plugins May 08 22:47:00 volumiomx volumio[9679]: info: Adding plugin smart_inputs to MyMusic Plugins May 08 22:47:00 volumiomx volumio[9679]: info: Adding plugin tidalconnect to MyMusic Plugins May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 08 22:47:00 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:00 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:00 volumiomx volumio[9679]: info: Starting MyVolumio Remote Streaming Endpoints May 08 22:47:00 volumiomx volumio[9679]: info: MyVolumio login type: Token May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 08 22:47:00 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 08 22:47:01 volumiomx volumio[9679]: info: Starting Streaming Service Transparent Proxy May 08 22:47:01 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 08 22:47:01 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 08 22:47:01 volumiomx volumio[9679]: info: Streaming services startup May 08 22:47:01 volumiomx volumio[9679]: info: Starting Streaming Daemon May 08 22:47:01 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 08 22:47:01 volumiomx sudo[9993]: volumio : unable to resolve host volumiomx May 08 22:47:01 volumiomx sudo[9993]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:01 volumiomx sudo[9993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 08 22:47:01 volumiomx sudo[9993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:01 volumiomx sudo[9993]: pam_unix(sudo:session): session closed for user root May 08 22:47:01 volumiomx volumio[9679]: info: Spotify volume: 100 May 08 22:47:01 volumiomx volumio[9679]: error: Cannot start Volumio Streaming Daemon May 08 22:47:01 volumiomx volumio[9679]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 08 22:47:01 volumiomx volumio[9679]: sudo: unable to resolve host volumiomx: System error May 08 22:47:01 volumiomx volumio[9679]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 08 22:47:01 volumiomx volumio[9679]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 08 22:47:01 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:47:01 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:01 volumiomx volumio[9679]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 22:47:01 volumiomx volumio[9679]: SPOTIFY: SPOTIFY VOLUME 100 May 08 22:47:01 volumiomx volumio[9679]: SPOTIFY: VOLUMIO VOLUME 0 May 08 22:47:01 volumiomx volumio[9679]: SPOTIFY: DELTA VOLUME ENOUGH: true May 08 22:47:01 volumiomx volumio[9679]: info: Setting Spotify Volume from Volumio: 0 May 08 22:47:01 volumiomx volumio[9679]: error: MyVolumio Custom Token format not valid, refreshing it May 08 22:47:02 volumiomx volumio[9679]: STREAMING PROXY: Starting server on port 3245 May 08 22:47:02 volumiomx volumio[9679]: Node JS runtime: 14 May 08 22:47:03 volumiomx volumio[9679]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 08 22:47:03 volumiomx volumio[9679]: info: MyVolumio login type: Token May 08 22:47:03 volumiomx volumio[9679]: SPOTIFY: SETTING SPOTIFY VOLUME 0 May 08 22:47:03 volumiomx volumio[9679]: info: Sending Spotify command with payload to local API: /player/volume May 08 22:47:03 volumiomx go-librespot[9924]: time="2024-05-08T22:47:03+03:00" level=debug msg="update volume to 0/65535" May 08 22:47:03 volumiomx go-librespot[9924]: time="2024-05-08T22:47:03+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 08 22:47:03 volumiomx go-librespot[9924]: time="2024-05-08T22:47:03+03:00" level=trace msg="emitting websocket event: volume" May 08 22:47:03 volumiomx volumio[9679]: SPOTIFY: received: {"type":"volume","data":{"value":0,"max":100}} May 08 22:47:03 volumiomx volumio[9679]: SPOTIFY: RECEIVED SPOTIFY VOLUME 0 May 08 22:47:03 volumiomx volumio[9679]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 08 22:47:04 volumiomx volumio[9679]: info: MyVolumio token set successfully May 08 22:47:04 volumiomx volumio[9679]: info: MYVOLUMIO: Adding device May 08 22:47:04 volumiomx volumio[9679]: info: MYVOLUMIO: Evaluating Server May 08 22:47:05 volumiomx volumio[9679]: info: MyVolumio Plan changed: premium May 08 22:47:05 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 08 22:47:05 volumiomx volumio[9679]: info: Removing browser output: myVolumio user plan is not superstar May 08 22:47:05 volumiomx volumio[9679]: info: Removing audio output: May 08 22:47:05 volumiomx volumio[9679]: info: MYVOLUMIO: Adding device May 08 22:47:05 volumiomx volumio[9679]: info: MYVOLUMIO: Evaluating Server May 08 22:47:05 volumiomx volumio[9679]: info: Remote config written successfully May 08 22:47:05 volumiomx volumio[9679]: info: Starting Tunnel 1 May 08 22:47:05 volumiomx volumio[9679]: info: Starting Tunnel Connection Checker May 08 22:47:05 volumiomx volumio[9679]: info: Completed starting MyVolumio Plugin May 08 22:47:05 volumiomx volumio[9679]: info: MYVolumio Device enabled May 08 22:47:05 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 08 22:47:05 volumiomx volumio[9679]: info: MyVolumio status changed May 08 22:47:05 volumiomx volumio[9679]: info: Streaming services startup May 08 22:47:05 volumiomx volumio[9679]: info: Starting Streaming Daemon May 08 22:47:05 volumiomx volumio[9679]: info: Setting Geolocation for MyVolumio to eu4 May 08 22:47:05 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:05 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:05 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:05 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 08 22:47:05 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 08 22:47:05 volumiomx sudo[10033]: volumio : unable to resolve host volumiomx May 08 22:47:05 volumiomx sudo[10033]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:05 volumiomx sudo[10033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 08 22:47:05 volumiomx sudo[10033]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:05 volumiomx sudo[10033]: pam_unix(sudo:session): session closed for user root May 08 22:47:05 volumiomx volumio[9679]: info: [1715197625896] Starting BluetoothController May 08 22:47:05 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 08 22:47:06 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 08 22:47:06 volumiomx sudo[10036]: volumio : unable to resolve host volumiomx May 08 22:47:06 volumiomx sudo[10036]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:06 volumiomx sudo[10036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 08 22:47:06 volumiomx sudo[10036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:06 volumiomx sudo[10036]: pam_unix(sudo:session): session closed for user root May 08 22:47:06 volumiomx volumio[9679]: sudo: unable to resolve host volumiomx: System error May 08 22:47:06 volumiomx volumio[9679]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists May 08 22:47:06 volumiomx volumio[9679]: info: MRS: MultiRoom plugin initialized May 08 22:47:06 volumiomx volumio[9679]: info: MRS: STOPPING SNAPCLIENT May 08 22:47:06 volumiomx volumio[9679]: info: MRS: Snap server stop May 08 22:47:06 volumiomx volumio[9679]: info: MRS: STOPPING volumioStreaming May 08 22:47:06 volumiomx sudo[10052]: volumio : unable to resolve host volumiomx May 08 22:47:06 volumiomx sudo[10052]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:06 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 08 22:47:06 volumiomx sudo[10052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 08 22:47:06 volumiomx sudo[10052]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:06 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 08 22:47:06 volumiomx sudo[10054]: volumio : unable to resolve host volumiomx May 08 22:47:06 volumiomx sudo[10054]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:06 volumiomx sudo[10052]: pam_unix(sudo:session): session closed for user root May 08 22:47:06 volumiomx sudo[10054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 08 22:47:06 volumiomx sudo[10054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:06 volumiomx sudo[10056]: volumio : unable to resolve host volumiomx May 08 22:47:06 volumiomx sudo[10056]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:06 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 08 22:47:06 volumiomx sudo[10056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 08 22:47:06 volumiomx sudo[10056]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:06 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 08 22:47:06 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 08 22:47:06 volumiomx sudo[10054]: pam_unix(sudo:session): session closed for user root May 08 22:47:06 volumiomx sudo[10056]: pam_unix(sudo:session): session closed for user root May 08 22:47:06 volumiomx sudo[10058]: volumio : unable to resolve host volumiomx May 08 22:47:06 volumiomx sudo[10058]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:06 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 08 22:47:06 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 08 22:47:06 volumiomx sudo[10058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 08 22:47:06 volumiomx sudo[10058]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:07 volumiomx sudo[10058]: pam_unix(sudo:session): session closed for user root May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 08 22:47:07 volumiomx volumio[9679]: info: Preparing to generate the ALSA configuration file May 08 22:47:07 volumiomx volumio[9679]: info: Updating MyVolumio device info May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 08 22:47:07 volumiomx volumio[9679]: info: Reading ALSA contributions from plugins. May 08 22:47:07 volumiomx volumio[9679]: info: Setting Geolocation for MyVolumio to eu7 May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: MRS: Removed streaming files May 08 22:47:07 volumiomx volumio[9679]: info: MRS: volumioStreaming STOPPED May 08 22:47:07 volumiomx volumio[9679]: info: MRS: SNAPSERVER STOPPED May 08 22:47:07 volumiomx volumio[9679]: info: MRS: SNAPCLIENT STOPPED May 08 22:47:07 volumiomx volumio[9679]: error: Cannot start Volumio Streaming Daemon May 08 22:47:07 volumiomx volumio[9679]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 08 22:47:07 volumiomx volumio[9679]: sudo: unable to resolve host volumiomx: System error May 08 22:47:07 volumiomx volumio[9679]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 08 22:47:07 volumiomx volumio[9679]: info: Asound.conf file written May 08 22:47:07 volumiomx sudo[10064]: volumio : unable to resolve host volumiomx May 08 22:47:07 volumiomx sudo[10064]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:07 volumiomx sudo[10064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 08 22:47:07 volumiomx sudo[10064]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:07 volumiomx sudo[10064]: pam_unix(sudo:session): session closed for user root May 08 22:47:07 volumiomx volumio[9679]: sudo: unable to resolve host volumiomx: System error May 08 22:47:07 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:47:07 volumiomx volumio[9679]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error May 08 22:47:07 volumiomx volumio[9679]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument May 08 22:47:07 volumiomx volumio[9679]: info: Output device has changed, restarting MPD May 08 22:47:07 volumiomx volumio[9679]: info: Output device has changed, restarting Shairport Sync May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 22:47:07 volumiomx volumio[9679]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 08 22:47:07 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output for this device May 08 22:47:07 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output May 08 22:47:07 volumiomx volumio[9679]: info: Adding audio output: May 08 22:47:07 volumiomx volumio[9679]: info: Adding audio output: May 08 22:47:07 volumiomx volumio[9679]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 08 22:47:07 volumiomx volumio[9679]: info: Adding METAVOLUMIO REST API Endpoints May 08 22:47:07 volumiomx volumio[9679]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 08 22:47:07 volumiomx volumio[9679]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 08 22:47:07 volumiomx volumio[9679]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 08 22:47:07 volumiomx volumio[9679]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 08 22:47:07 volumiomx volumio[9679]: info: Adding Manifest REST API Endpoints May 08 22:47:07 volumiomx volumio[9679]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 08 22:47:07 volumiomx volumio[9679]: info: Preparing CD Folders May 08 22:47:07 volumiomx volumio[9679]: info: Adding CD REST API Endpoints May 08 22:47:07 volumiomx volumio[9679]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 08 22:47:07 volumiomx volumio[9679]: info: Starting UDEV Watcher for CD May 08 22:47:07 volumiomx sudo[10071]: volumio : unable to resolve host volumiomx May 08 22:47:07 volumiomx sudo[10071]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:07 volumiomx volumio[9679]: info: Detecting CD presence with UDEV May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 08 22:47:07 volumiomx sudo[10069]: volumio : unable to resolve host volumiomx May 08 22:47:07 volumiomx sudo[10069]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:07 volumiomx sudo[10071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 22:47:07 volumiomx sudo[10071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:07 volumiomx sudo[10069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 22:47:07 volumiomx sudo[10069]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:07 volumiomx sudo[10069]: pam_unix(sudo:session): session closed for user root May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 08 22:47:07 volumiomx systemd[1]: Stopping Music Player Daemon... May 08 22:47:07 volumiomx volumio[9679]: info: Adding inputs REST Endpoints May 08 22:47:07 volumiomx volumio[9679]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 08 22:47:07 volumiomx volumio[9679]: info: Scanning Audio Inputs May 08 22:47:07 volumiomx systemd[1]: mpd.service: Succeeded. May 08 22:47:07 volumiomx systemd[1]: Stopped Music Player Daemon. May 08 22:47:07 volumiomx volumio[9679]: info: Checking against Known Cards name May 08 22:47:07 volumiomx volumio[9679]: info: Adding Server instance for streaming May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 08 22:47:07 volumiomx volumio[9679]: error: Hi Res Audio Failed Login: Missing Login Data May 08 22:47:07 volumiomx volumio[9679]: info: Adding HIGHRESAUDIO REST API Endpoints May 08 22:47:07 volumiomx volumio[9679]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 08 22:47:07 volumiomx volumio[9679]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 08 22:47:07 volumiomx sudo[10078]: volumio : unable to resolve host volumiomx May 08 22:47:07 volumiomx sudo[10078]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:07 volumiomx sudo[10078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio_mx May 08 22:47:07 volumiomx sudo[10078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:07 volumiomx sudo[10078]: pam_unix(sudo:session): session closed for user root May 08 22:47:07 volumiomx systemd[1]: Starting Music Player Daemon... May 08 22:47:07 volumiomx volumio[9679]: info: Stopping AccessToken refresher cron May 08 22:47:07 volumiomx volumio[9679]: info: AccessToken refresher cron started May 08 22:47:07 volumiomx volumio[9679]: info: Stopping AccessToken refresher cron for QOBUZ May 08 22:47:07 volumiomx volumio[9679]: info: AccessToken refresher cron started for QOBUZ May 08 22:47:07 volumiomx volumio[9679]: info: Adding TIDAL REST API Endpoints May 08 22:47:07 volumiomx volumio[9679]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 08 22:47:07 volumiomx volumio[9679]: info: Adding QOBUZ REST API Endpoints May 08 22:47:07 volumiomx volumio[9679]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 08 22:47:07 volumiomx volumio[9679]: info: Bluetooth name changed to Volumio_mx May 08 22:47:07 volumiomx volumio[9679]: info: MPD Permissions set May 08 22:47:07 volumiomx sudo[10089]: volumio : unable to resolve host volumiomx May 08 22:47:07 volumiomx sudo[10089]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:07 volumiomx sudo[10089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 22:47:07 volumiomx sudo[10089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:07 volumiomx systemd[1]: Stopping Volumio Tidal Connect Service... May 08 22:47:07 volumiomx systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 08 22:47:07 volumiomx systemd[1]: vtcs.service: Succeeded. May 08 22:47:07 volumiomx systemd[1]: Stopped Volumio Tidal Connect Service. May 08 22:47:07 volumiomx sudo[10089]: pam_unix(sudo:session): session closed for user root May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:07 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:08 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:08 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:08 volumiomx volumio[9679]: info: Updating MyVolumio device info May 08 22:47:08 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:08 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:08 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:08 volumiomx volumio[9679]: info: Starting Shairport Sync May 08 22:47:08 volumiomx sudo[10098]: volumio : unable to resolve host volumiomx May 08 22:47:08 volumiomx sudo[10098]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:08 volumiomx sudo[10098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 22:47:08 volumiomx sudo[10098]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:08 volumiomx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 22:47:08 volumiomx systemd[1]: shairport-sync.service: Succeeded. May 08 22:47:08 volumiomx systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 22:47:08 volumiomx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 22:47:08 volumiomx sudo[10098]: pam_unix(sudo:session): session closed for user root May 08 22:47:08 volumiomx volumio[9679]: info: Shairport-Sync Started May 08 22:47:08 volumiomx mpd[10085]: May 08 22:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 08 22:47:08 volumiomx systemd[1]: Started Music Player Daemon. May 08 22:47:08 volumiomx sudo[10071]: pam_unix(sudo:session): session closed for user root May 08 22:47:08 volumiomx volumio[9679]: error: updateQueue error: null May 08 22:47:09 volumiomx volumio[9679]: info: Successfully Updated MyVolumio device May 08 22:47:09 volumiomx volumio[9679]: info: Successfully Added MyVolumio device May 08 22:47:09 volumiomx volumio[9679]: info: Successfully Updated MyVolumio device May 08 22:47:09 volumiomx volumio[9679]: info: Successfully Added MyVolumio device May 08 22:47:10 volumiomx sudo[10112]: volumio : unable to resolve host volumiomx May 08 22:47:10 volumiomx sudo[10112]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:10 volumiomx sudo[10112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 08 22:47:10 volumiomx sudo[10112]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:10 volumiomx autossh[3500]: received signal to exit (15) May 08 22:47:10 volumiomx systemd[1]: Stopping MyVolumio SSH Tunnel... May 08 22:47:10 volumiomx systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 08 22:47:10 volumiomx systemd[1]: sshtunnel.service: Succeeded. May 08 22:47:10 volumiomx systemd[1]: Stopped MyVolumio SSH Tunnel. May 08 22:47:10 volumiomx volumio[9679]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 22:47:10 volumiomx volumio[9679]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 22:47:10 volumiomx systemd[1]: Started MyVolumio SSH Tunnel. May 08 22:47:10 volumiomx sudo[10112]: pam_unix(sudo:session): session closed for user root May 08 22:47:10 volumiomx volumio[9679]: info: Remote SSH Started May 08 22:47:10 volumiomx autossh[10118]: port set to 0, monitoring disabled May 08 22:47:10 volumiomx autossh[10118]: starting ssh (count 1) May 08 22:47:10 volumiomx autossh[10118]: ssh child pid is 10121 May 08 22:47:10 volumiomx sudo[10114]: volumio : unable to resolve host volumiomx May 08 22:47:10 volumiomx sudo[10114]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:10 volumiomx sudo[10114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 08 22:47:10 volumiomx sudo[10114]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:10 volumiomx sudo[10114]: pam_unix(sudo:session): session closed for user root May 08 22:47:10 volumiomx volumio[9679]: info: Volumio BT Module successfully started May 08 22:47:10 volumiomx volumio[9679]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 May 08 22:47:10 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:47:10 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:10 volumiomx volumio[9679]: error: Unable to stop TidalConnect service: May 08 22:47:10 volumiomx volumio[9679]: info: TidalConnect service stoped! May 08 22:47:11 volumiomx volumio[9679]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 08 22:47:11 volumiomx volumio[9679]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 08 22:47:11 volumiomx sudo[10132]: volumio : unable to resolve host volumiomx May 08 22:47:11 volumiomx sudo[10132]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:11 volumiomx sudo[10132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 08 22:47:11 volumiomx sudo[10132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:11 volumiomx volumiossh-tunnel[10116]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. May 08 22:47:11 volumiomx systemd[1]: Started Volumio Tidal Connect Service. May 08 22:47:11 volumiomx sudo[10132]: pam_unix(sudo:session): session closed for user root May 08 22:47:11 volumiomx volumio[9679]: info: Executing endpoint tc_getconfig May 08 22:47:11 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 08 22:47:11 volumiomx vtcs[10134]: STARTING TidalConnect services, version: 1.4.0.34 May 08 22:47:11 volumiomx vtcs[10134]: STARTED TidalConnect services. May 08 22:47:11 volumiomx volumio[9679]: info: Executing endpoint tc_connect May 08 22:47:11 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 08 22:47:11 volumiomx volumio[9679]: info: Connecting to TidalConnect May 08 22:47:11 volumiomx volumio[9679]: info: CoreCommandRouter::servicePushState May 08 22:47:11 volumiomx volumio[9679]: info: CoreStateMachine::pushState May 08 22:47:11 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:11 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPushState May 08 22:47:11 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output update for this device May 08 22:47:11 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output May 08 22:47:11 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:11 volumiomx volumio[9679]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 08 22:47:11 volumiomx volumio[9679]: info: CoreCommandRouter::servicePushState May 08 22:47:11 volumiomx volumio[9679]: info: CoreStateMachine::pushState May 08 22:47:11 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:11 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPushState May 08 22:47:11 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output update for this device May 08 22:47:11 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output May 08 22:47:11 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:11 volumiomx volumio[9679]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 08 22:47:11 volumiomx volumio[9679]: info: [ASDebug] CurState: stop PrevState: stop May 08 22:47:11 volumiomx volumio[9679]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 22:47:11 volumiomx volumio[9679]: info: [ASDebug] CurState: stop PrevState: stop May 08 22:47:11 volumiomx volumio[9679]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 22:47:12 volumiomx volumio[9679]: info: MRS: Getting audio outputs on start May 08 22:47:12 volumiomx volumio[9679]: info: MRS: Requesting all other devices output May 08 22:47:15 volumiomx volumio[9679]: error: Unable to start TidalConnect service: May 08 22:47:15 volumiomx volumio[9679]: info: TidalConnect service started! May 08 22:47:15 volumiomx volumio[9679]: info: BOOT COMPLETED May 08 22:47:15 volumiomx volumio[9679]: [Metrics] CommandRouter: 33s 613.53ms May 08 22:47:15 volumiomx volumio[9679]: info: CoreCommandRouter::volumiosetStartupVolume May 08 22:47:15 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:15 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:15 volumiomx volumio[9679]: info: CoreCommandRouter::Close All Modals sent May 08 22:47:15 volumiomx volumio[9679]: info: CoreCommandRouter::Close All Modals sent May 08 22:47:15 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:47:15 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:15 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:47:15 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:47:15 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 22:47:15 volumiomx volumio[9679]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 May 08 22:47:16 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:47:16 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:16 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 08 22:47:16 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 22:47:16 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 08 22:47:17 volumiomx volumio[9679]: info: Executing endpoint metavolumio May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:47:17 volumiomx volumio[9679]: info: Executing endpoint metavolumio May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:47:17 volumiomx volumio[9679]: info: Executing endpoint metavolumio May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 22:47:17 volumiomx volumio-remote-updater[732]: Test mode enabled May 08 22:47:17 volumiomx volumio-remote-updater[732]: No alpha test mode May 08 22:47:17 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 08 22:47:18 volumiomx volumio[9679]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 08 22:47:18 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 08 22:47:20 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:47:20 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:20 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 22:47:20 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 08 22:47:20 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 08 22:47:20 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 08 22:47:20 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetBrowseSources May 08 22:47:20 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 22:47:21 volumiomx volumio[9679]: info: [ASDebug] Port: 24 May 08 22:47:21 volumiomx volumio[9679]: info: [ASDebug] Inverted: false May 08 22:47:21 volumiomx volumio[9679]: info: [ASDebug] Delay: 720 May 08 22:47:34 volumiomx volumio[9679]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 22:47:40 volumiomx volumio[9679]: info: CALLMETHOD: system_hardware audiophonics_evo_sabre restartOled May 08 22:47:40 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: audiophonics_evo_sabre , restartOled May 08 22:47:40 volumiomx sudo[10202]: volumio : unable to resolve host volumiomx May 08 22:47:40 volumiomx sudo[10202]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:40 volumiomx sudo[10202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service May 08 22:47:40 volumiomx sudo[10202]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:40 volumiomx systemd[1]: Started OLED Display Service for EVO SABRE. May 08 22:47:40 volumiomx sudo[10202]: pam_unix(sudo:session): session closed for user root May 08 22:47:40 volumiomx volumio[9679]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success May 08 22:47:40 volumiomx sudo[10204]: root : unable to resolve host volumiomx May 08 22:47:40 volumiomx sudo[10204]: root : problem with defaults entries ; TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; May 08 22:47:40 volumiomx sudo[10204]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio May 08 22:47:40 volumiomx sudo[10204]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 22:47:42 volumiomx volumio[9679]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 May 08 22:47:42 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:47:42 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:42 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetQueue May 08 22:47:42 volumiomx volumio[9679]: info: CoreStateMachine::getQueue May 08 22:47:42 volumiomx volumio[9679]: info: CorePlayQueue::getQueue May 08 22:47:42 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:47:42 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:48 volumiomx volumio[9679]: info: CoreCommandRouter::volumioGetState May 08 22:47:48 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:50 volumiomx volumio[9679]: info: Executing endpoint metavolumio May 08 22:47:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:47:50 volumiomx volumio[9679]: info: Executing endpoint metavolumio May 08 22:47:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:47:50 volumiomx volumio[9679]: info: Executing endpoint metavolumio May 08 22:47:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 08 22:47:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPlay May 08 22:47:50 volumiomx volumio[9679]: info: CoreStateMachine::play index undefined May 08 22:47:50 volumiomx volumio[9679]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 22:47:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:50 volumiomx volumio[9679]: info: CoreStateMachine::startPlaybackTimer May 08 22:47:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAs/Pink Floyd - Collection (1967 -2014) FLAC (24bit-44kHz) vtwin88cube/1967 - The Piper At The Gates Of Dawn (Mono)/01.-Astronomy Domine.flac May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::sendMpdCommand stop May 08 22:47:50 volumiomx volumio[9679]: info: sendMpdCommand stop took 2 milliseconds May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::sendMpdCommand clear May 08 22:47:50 volumiomx volumio[9679]: info: May 08 22:47:50 volumiomx volumio[9679]: ---------------------------- MPD announces system playlist update May 08 22:47:50 volumiomx volumio[9679]: info: Ignoring MPD Status Update May 08 22:47:50 volumiomx volumio[9679]: info: sendMpdCommand clear took 2 milliseconds May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAs/Pink Floyd - Collection (1967 -2014) FLAC (24bit-44kHz) vtwin88cube/1967 - The Piper At The Gates Of Dawn (Mono)/01.-Astronomy Domine.flac" May 08 22:47:50 volumiomx volumio[9679]: error: updateQueue error: null May 08 22:47:50 volumiomx volumio[9679]: info: May 08 22:47:50 volumiomx volumio[9679]: ---------------------------- MPD announces system playlist update May 08 22:47:50 volumiomx volumio[9679]: info: Ignoring MPD Status Update May 08 22:47:50 volumiomx volumio[9679]: info: ------------------------------ 4ms May 08 22:47:50 volumiomx volumio[9679]: info: sendMpdCommand add "NAS/NAs/Pink Floyd - Collection (1967 -2014) FLAC (24bit-44kHz) vtwin88cube/1967 - The Piper At The Gates Of Dawn (Mono)/01.-Astronomy Domine.flac" took 3 milliseconds May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::sendMpdCommand play May 08 22:47:50 volumiomx volumio[9679]: info: ------------------------------ 3ms May 08 22:47:50 volumiomx volumio[9679]: info: sendMpdCommand play took 2 milliseconds May 08 22:47:50 volumiomx volumio[9679]: info: May 08 22:47:50 volumiomx volumio[9679]: ---------------------------- MPD announces state update: player May 08 22:47:50 volumiomx volumio[9679]: info: ControllerMpd::getState May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::sendMpdCommand status May 08 22:47:50 volumiomx volumio[9679]: info: May 08 22:47:50 volumiomx volumio[9679]: ---------------------------- MPD announces state update: player May 08 22:47:50 volumiomx volumio[9679]: info: sendMpdCommand status took 11 milliseconds May 08 22:47:50 volumiomx volumio[9679]: info: ControllerMpd::getState May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::sendMpdCommand status May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::parseState May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 22:47:50 volumiomx volumio[9679]: info: sendMpdCommand status took 3 milliseconds May 08 22:47:50 volumiomx volumio[9679]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::parseState May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::parseTrackInfo May 08 22:47:50 volumiomx volumio[9679]: info: ControllerMpd::pushState May 08 22:47:50 volumiomx volumio[9679]: info: CoreCommandRouter::servicePushState May 08 22:47:50 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:47:50 volumiomx kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 08 22:47:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:50 volumiomx volumio[9679]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Astronomy Domine","artist":"Pink Floyd","album":"The Piper At The Gates Of Dawn","uri":"NAS/NAs/Pink Floyd - Collection (1967 -2014) FLAC (24bit-44kHz) vtwin88cube/1967 - The Piper At The Gates Of Dawn (Mono)/01.-Astronomy Domine.flac","trackType":"flac"} May 08 22:47:50 volumiomx volumio[9679]: verbose: CURRENT POSITION 0 May 08 22:47:50 volumiomx volumio[9679]: info: CoreStateMachine::syncState stateService play May 08 22:47:50 volumiomx volumio[9679]: info: CoreStateMachine::syncState currentStatus stop May 08 22:47:50 volumiomx volumio[9679]: info: ------------------------------ 20ms May 08 22:47:50 volumiomx volumio[9679]: info: sendMpdCommand playlistinfo took 5 milliseconds May 08 22:47:50 volumiomx volumio[9679]: verbose: ControllerMpd::parseTrackInfo May 08 22:47:50 volumiomx volumio[9679]: info: ControllerMpd::pushState May 08 22:47:50 volumiomx volumio[9679]: info: CoreCommandRouter::servicePushState May 08 22:47:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:50 volumiomx volumio[9679]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Astronomy Domine","artist":"Pink Floyd","album":"The Piper At The Gates Of Dawn","uri":"NAS/NAs/Pink Floyd - Collection (1967 -2014) FLAC (24bit-44kHz) vtwin88cube/1967 - The Piper At The Gates Of Dawn (Mono)/01.-Astronomy Domine.flac","trackType":"flac"} May 08 22:47:50 volumiomx volumio[9679]: verbose: CURRENT POSITION 0 May 08 22:47:50 volumiomx volumio[9679]: info: CoreStateMachine::syncState stateService play May 08 22:47:50 volumiomx volumio[9679]: info: CoreStateMachine::syncState currentStatus play May 08 22:47:50 volumiomx volumio[9679]: info: Received an update from plugin. extracting info from payload May 08 22:47:50 volumiomx volumio[9679]: info: CoreStateMachine::pushState May 08 22:47:50 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:50 volumiomx volumio[9679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 22:47:50 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPushState May 08 22:47:51 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output update for this device May 08 22:47:51 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output May 08 22:47:51 volumiomx volumio[9679]: info: CoreStateMachine::pushState May 08 22:47:51 volumiomx volumio[9679]: info: CorePlayQueue::getTrack 0 May 08 22:47:51 volumiomx volumio[9679]: info: CoreCommandRouter::volumioPushState May 08 22:47:51 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output update for this device May 08 22:47:51 volumiomx volumio[9679]: info: MRS: Pushing multiroomSync output May 08 22:47:51 volumiomx volumio[9679]: info: ------------------------------ 51ms May 08 22:47:51 volumiomx volumio[9679]: info: [ASDebug] CurState: play PrevState: stop May 08 22:47:51 volumiomx volumio[9679]: info: [ASDebug] Togle GPIO: ON May 08 22:47:51 volumiomx volumio[9679]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 22:47:51 volumiomx volumio[9679]: Error: ESPIPE: invalid seek, write May 08 22:47:51 volumiomx volumio[9679]: at Object.writeSync (fs.js:711:3) May 08 22:47:51 volumiomx volumio[9679]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 08 22:47:51 volumiomx volumio[9679]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:199:23) May 08 22:47:51 volumiomx volumio[9679]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:182:86) May 08 22:47:51 volumiomx volumio[9679]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 08 22:47:51 volumiomx volumio[9679]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) May 08 22:47:51 volumiomx volumio[9679]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) May 08 22:47:51 volumiomx volumio[9679]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 08 22:47:51 volumiomx volumio[9679]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 08 22:47:51 volumiomx volumio[9679]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) May 08 22:47:51 volumiomx volumio[9679]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 08 22:47:51 volumiomx volumio[9679]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 08 22:47:51 volumiomx volumio[9679]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:251:12) May 08 22:47:51 volumiomx volumio[9679]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) May 08 22:47:51 volumiomx volumio[9679]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 08 22:47:51 volumiomx volumio[9679]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { May 08 22:47:51 volumiomx volumio[9679]: errno: -29, May 08 22:47:51 volumiomx volumio[9679]: syscall: 'write', May 08 22:47:51 volumiomx volumio[9679]: code: 'ESPIPE' May 08 22:47:51 volumiomx volumio[9679]: } May 08 22:47:51 volumiomx volumio[9679]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 22:47:51 volumiomx sudo[10231]: volumio : unable to resolve host volumiomx May 08 22:47:51 volumiomx sudo[10231]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; May 08 22:47:51 volumiomx sudo[10231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 22:46 May 08 22:47:51 volumiomx sudo[10231]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a208c5f21253c17c42c91c577f18ec4684ba728c" VOLUMIO_FE_VERSION="4046dddc0717a69457a40270e2fcbc46f9ee4c24" VOLUMIO_FE3_VERSION="19db4344fa87f8eaaab1560f7319710ca849c94e" VOLUMIO_BE_VERSION="ba091b8f986f3874addae313357b33cb364a9907" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 08 May 2024 12:03:52 PM CEST" VOLUMIO_VERSION="3.669" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6d27b24987523e179342859bf3562c2c"