-- 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"