-- Logs begin at Tue 2024-09-24 13:56:20 CEST, end at Tue 2024-09-24 13:58:46 CEST. -- Sep 24 13:57:00 rivo volumio[3100]: info: Checking for updated MCU Firmware Sep 24 13:57:00 rivo volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:57:00 rivo volumio[3100]: info: Firware on device is on latest version, no need to update Sep 24 13:57:00 rivo volumio[3100]: info: Cannot mount NAS Tp-share at system boot, trial number 2 ,retrying in 5 seconds Sep 24 13:57:05 rivo sudo[3540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=TubeDAC,password=spliTT67#,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.219/To-share/Lossless /mnt/NAS/Tp-share Sep 24 13:57:05 rivo sudo[3540]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:57:05 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Sep 24 13:57:08 rivo kernel: fb: mem_free_work, free memory: addr:800000 Sep 24 13:57:09 rivo volumio[3100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 13:57:09 rivo volumio[3100]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Sep 24 13:57:09 rivo volumio[3100]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Sep 24 13:57:09 rivo volumio[3100]: errno: -3007, Sep 24 13:57:09 rivo volumio[3100]: code: 'ENOTFOUND', Sep 24 13:57:09 rivo volumio[3100]: syscall: 'getaddrinfo', Sep 24 13:57:09 rivo volumio[3100]: hostname: 'ws.audioscrobbler.com' Sep 24 13:57:09 rivo volumio[3100]: } Sep 24 13:57:09 rivo volumio[3100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 13:57:09 rivo sudo[3556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-24 13:56 Sep 24 13:57:09 rivo sudo[3556]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:57:10 rivo sudo[3556]: pam_unix(sudo:session): session closed for user root Sep 24 13:57:11 rivo sudo[3540]: pam_unix(sudo:session): session closed for user root Sep 24 13:57:11 rivo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Sep 24 13:57:11 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -113 Sep 24 13:57:12 rivo volumio[3442]: .................................................................................................................................................................................................................................................................................++++ Sep 24 13:57:16 rivo volumio[3442]: .................................................++++ Sep 24 13:57:16 rivo volumio[3442]: e is 65537 (0x010001) Sep 24 13:57:16 rivo volumio[3442]: writing RSA key Sep 24 13:57:27 rivo volumio-remote-updater[2844]: [2024-09-24 13:57:27] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 24 13:57:32 rivo volumio-remote-updater[2844]: [2024-09-24 13:57:32] [connect] Successful connection Sep 24 13:57:44 rivo volumio-remote-updater[2844]: [2024-09-24 13:57:44] [connect] Successful connection Sep 24 13:57:50 rivo ntpd[3065]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 24 13:57:59 rivo volumio-remote-updater[2844]: [2024-09-24 13:57:59] [connect] Successful connection Sep 24 13:58:09 rivo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 24 13:58:09 rivo sudo[3531]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:09 rivo systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 24 13:58:09 rivo systemd[1]: Started dynamicswap service. Sep 24 13:58:09 rivo systemd[1]: dynamicswap.service: Succeeded. Sep 24 13:58:09 rivo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 24 13:58:09 rivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 24 13:58:09 rivo systemd[1]: Stopped Volumio Backend Module. Sep 24 13:58:09 rivo systemd[1]: Started Volumio Backend Module. Sep 24 13:58:09 rivo systemd[1]: Started dynamicswap service. Sep 24 13:58:09 rivo systemd[1]: dynamicswap.service: Succeeded. Sep 24 13:58:10 rivo ntpd[3065]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 24 13:58:11 rivo volumio[3627]: info: ------------------------------------------- Sep 24 13:58:11 rivo volumio[3627]: info: ----- Volumio3 ---- Sep 24 13:58:11 rivo volumio[3627]: info: ------------------------------------------- Sep 24 13:58:11 rivo volumio[3627]: info: ----- System startup ---- Sep 24 13:58:11 rivo volumio[3627]: info: ------------------------------------------- Sep 24 13:58:12 rivo volumio[3627]: info: MYVOLUMIO Environment detected Sep 24 13:58:12 rivo volumio[3627]: info: Plugin folders cleanup Sep 24 13:58:12 rivo volumio[3627]: info: Scanning into folder /volumio/app/plugins/ Sep 24 13:58:12 rivo volumio[3627]: info: Scanning category audio_interface Sep 24 13:58:12 rivo volumio[3627]: info: Scanning category miscellanea Sep 24 13:58:12 rivo volumio[3627]: info: Scanning category music_service Sep 24 13:58:12 rivo volumio[3627]: info: Scanning category plugins.json Sep 24 13:58:12 rivo volumio[3627]: info: Scanning category system_controller Sep 24 13:58:12 rivo volumio[3627]: info: Scanning category user_interface Sep 24 13:58:12 rivo volumio[3627]: info: Scanning into folder /data/plugins/ Sep 24 13:58:12 rivo volumio[3627]: info: Scanning category music_service Sep 24 13:58:12 rivo volumio[3627]: info: Scanning category user_interface Sep 24 13:58:12 rivo volumio[3627]: info: Plugin folders cleanup completed Sep 24 13:58:12 rivo volumio[3627]: info: ------------------------------------------- Sep 24 13:58:12 rivo volumio[3627]: info: ----- Core plugins startup ---- Sep 24 13:58:12 rivo volumio[3627]: info: ------------------------------------------- Sep 24 13:58:12 rivo volumio[3627]: info: Loading plugins from folder /volumio/app/plugins/ Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin bluetooth to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin multiroom to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin upnp to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin metavolumio to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin cd_controller to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin raat to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 24 13:58:12 rivo volumio[3627]: info: Loading plugins from folder /data/plugins/ Sep 24 13:58:12 rivo volumio[3627]: info: Loading plugin "system"... Sep 24 13:58:12 rivo volumio[3627]: info: Loading plugin "appearance"... Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "network"... Sep 24 13:58:14 rivo volumio[3627]: info: Refreshing Cached IP Addresses Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "services"... Sep 24 13:58:14 rivo sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 24 13:58:14 rivo sudo[3654]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "alsa_controller"... Sep 24 13:58:14 rivo sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 24 13:58:14 rivo sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo sudo[3654]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:14 rivo sudo[3656]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:14 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "wizard"... Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "networkfs"... Sep 24 13:58:14 rivo volumio[3627]: info: Starting Udev Watcher for removable devices Sep 24 13:58:14 rivo sudo[3665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.2.100:/volume1/music /mnt/NAS/Synology Sep 24 13:58:14 rivo sudo[3665]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=TubeDAC,password=spliTT67#,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.219/To-share/Lossless /mnt/NAS/Tp-share Sep 24 13:58:14 rivo sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo volumio[3627]: info: Mounting Device issd Sep 24 13:58:14 rivo volumio[3627]: /bin/mkdir: cannot create directory ‘/mnt/INTERNAL/’: File exists Sep 24 13:58:14 rivo volumio[3627]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/INTERNAL/" Sep 24 13:58:14 rivo volumio[3627]: /bin/mkdir: cannot create directory ‘/mnt/INTERNAL/’: File exists Sep 24 13:58:14 rivo sudo[3679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/mmcblk1p1 /mnt/INTERNAL/ -o noatime Sep 24 13:58:14 rivo sudo[3679]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo sudo[3679]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:14 rivo volumio[3627]: fuse: bad mount point `/mnt/INTERNAL/': Transport endpoint is not connected Sep 24 13:58:14 rivo volumio[3627]: error: Failed to mount issd: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/mmcblk1p1" "/mnt/INTERNAL/" -o noatime Sep 24 13:58:14 rivo volumio[3627]: fuse: bad mount point `/mnt/INTERNAL/': Transport endpoint is not connected Sep 24 13:58:14 rivo volumio[3627]: info: Ignoring mount for partition: BOOT Sep 24 13:58:14 rivo volumio[3627]: info: Ignoring mount for partition: volumio Sep 24 13:58:14 rivo volumio[3627]: info: Ignoring mount for partition: volumio_data Sep 24 13:58:14 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "volumio_command_line_client"... Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "upnp"... Sep 24 13:58:14 rivo volumio[3627]: info: [1727179094568] Starting Upmpd Daemon Sep 24 13:58:14 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "my_music"... Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "mpd"... Sep 24 13:58:14 rivo volumio-remote-updater[2844]: [2024-09-24 13:58:14] [connect] Successful connection Sep 24 13:58:14 rivo volumio[3627]: info: Loading plugin "upnp_browser"... Sep 24 13:58:15 rivo volumio[3627]: info: Loading plugin "bluetooth"... Sep 24 13:58:15 rivo volumio[3627]: info: [1727179095493] Starting BluetoothController Sep 24 13:58:15 rivo volumio[3627]: info: Loading plugin "alarm-clock"... Sep 24 13:58:15 rivo volumio[3627]: info: Loading plugin "manifestui"... Sep 24 13:58:15 rivo volumio[3627]: info: Loading plugin "metavolumio"... Sep 24 13:58:17 rivo volumio[3627]: info: Loading plugin "airplay_emulation"... Sep 24 13:58:17 rivo volumio[3627]: info: Starting Shairport Sync Sep 24 13:58:17 rivo volumio[3627]: info: Plugin cd_controller is not enabled Sep 24 13:58:17 rivo volumio[3627]: info: Loading plugin "last_100"... Sep 24 13:58:17 rivo volumio[3627]: info: Plugin raat is not enabled Sep 24 13:58:17 rivo volumio[3627]: info: Loading plugin "streaming_services"... Sep 24 13:58:18 rivo volumio[3627]: info: Starting Streaming Service Transparent Proxy Sep 24 13:58:18 rivo volumio[3627]: info: Plugin tidalconnect is not enabled Sep 24 13:58:18 rivo volumio[3627]: info: Loading plugin "webradio"... Sep 24 13:58:18 rivo volumio[3627]: info: Loading plugin "i2s_dacs"... Sep 24 13:58:18 rivo volumio[3627]: info: I2S DAC not set, start Auto-detection Sep 24 13:58:18 rivo volumio[3627]: info: Loading plugin "volumiodiscovery"... Sep 24 13:58:18 rivo volumio[3627]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 24 13:58:18 rivo volumio[3627]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 24 13:58:18 rivo node[3627]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 24 13:58:18 rivo volumio[3627]: *** WARNING *** For more information see Sep 24 13:58:18 rivo volumio[3627]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 24 13:58:18 rivo volumio[3627]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 24 13:58:18 rivo volumio[3627]: *** WARNING *** For more information see Sep 24 13:58:18 rivo node[3627]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 24 13:58:18 rivo node[3627]: *** WARNING *** For more information see Sep 24 13:58:18 rivo node[3627]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 24 13:58:18 rivo node[3627]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 24 13:58:18 rivo node[3627]: *** WARNING *** For more information see Sep 24 13:58:18 rivo volumio[3627]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 24 13:58:18 rivo volumio[3627]: info: Discovery: Started advertising with name: Rivo Sep 24 13:58:18 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 24 13:58:18 rivo volumio[3627]: info: Plugin calmradio is not enabled Sep 24 13:58:18 rivo volumio[3627]: info: Plugin multiroom is not enabled Sep 24 13:58:18 rivo volumio[3627]: info: Loading plugin "outputs"... Sep 24 13:58:18 rivo volumio[3627]: info: Loading plugin "albumart"... Sep 24 13:58:18 rivo volumio[3627]: info: Plugin example_plugin is not enabled Sep 24 13:58:18 rivo volumio[3627]: info: Loading plugin "hi_res_audio"... Sep 24 13:58:18 rivo volumio[3627]: Forking 3 albumart workers Sep 24 13:58:19 rivo volumio[3627]: STREAMING PROXY: Starting server on port 3245 Sep 24 13:58:19 rivo volumio[3627]: Node JS runtime: 14 Sep 24 13:58:19 rivo volumio[3627]: info: Applying required configuration parameters for plugin hi_res_audio Sep 24 13:58:19 rivo volumio[3627]: info: Loading plugin "inputs"... Sep 24 13:58:20 rivo volumio[3627]: Starting albumart workers Sep 24 13:58:20 rivo volumio[3627]: Starting albumart workers Sep 24 13:58:20 rivo volumio[3627]: Starting albumart workers Sep 24 13:58:20 rivo volumio[3627]: info: Loading plugin "qobuz"... Sep 24 13:58:20 rivo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Sep 24 13:58:20 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -113 Sep 24 13:58:20 rivo sudo[3667]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:21 rivo volumio[3627]: info: Plugin smart_inputs is not enabled Sep 24 13:58:21 rivo volumio[3627]: info: Loading plugin "tidal"... Sep 24 13:58:22 rivo volumio[3627]: info: Loading plugin "rivocontrol"... Sep 24 13:58:23 rivo volumio[3627]: info: Adding this device properties Sep 24 13:58:23 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Sep 24 13:58:23 rivo volumio[3627]: info: Setting Additional Device Volumio Properties: [object Object] Sep 24 13:58:23 rivo volumio[3627]: info: Loading plugin "updater_comm"... Sep 24 13:58:23 rivo volumio[3627]: info: Loading plugin "cec_controller"... Sep 24 13:58:23 rivo volumio[3627]: info: Applying required configuration parameters for plugin cec_controller Sep 24 13:58:23 rivo volumio[3627]: info: Plugin mpdemulation is not enabled Sep 24 13:58:23 rivo volumio[3627]: info: Loading plugin "rest_api"... Sep 24 13:58:23 rivo volumio[3627]: info: Loading plugin "websocket"... Sep 24 13:58:23 rivo volumio[3627]: info: Starting Socket.io Server version 2.3.0 Sep 24 13:58:23 rivo volumio[3627]: info: Plugin 80s80s is not enabled Sep 24 13:58:23 rivo volumio[3627]: info: Plugin motherearthradio is not enabled Sep 24 13:58:23 rivo volumio[3627]: info: Plugin radio_paradise is not enabled Sep 24 13:58:23 rivo volumio[3627]: info: Plugin Systeminfo is not enabled Sep 24 13:58:23 rivo volumio[3627]: info: Loading plugin "lastfm"... Sep 24 13:58:24 rivo volumio[3627]: info: Plugin randomizer is not enabled Sep 24 13:58:24 rivo volumio[3627]: info: Loading i18n strings for locale en Sep 24 13:58:24 rivo volumio[3627]: Updating browse sources language Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::initPlayerControls Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 24 13:58:24 rivo volumio[3627]: Express server listening on port 3000 Sep 24 13:58:24 rivo volumio[3627]: [Metrics] WebUI: 13s 193.50ms Sep 24 13:58:24 rivo volumio[3627]: info: CoreStateMachine::resetVolumioState Sep 24 13:58:24 rivo volumio[3627]: info: CoreStateMachine::getcurrentVolume Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::volumioRetrievevolume Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:24 rivo sudo[3791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 24 13:58:24 rivo sudo[3791]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:24 rivo volumio[3627]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 24 13:58:24 rivo sudo[3793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 24 13:58:24 rivo sudo[3791]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:24 rivo volumio[3627]: info: Completed loading Core Plugins Sep 24 13:58:24 rivo sudo[3793]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:24 rivo volumio[3627]: info: Preparing to generate the ALSA configuration file Sep 24 13:58:24 rivo sudo[3793]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:24 rivo volumio[3627]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 24 13:58:24 rivo volumio[3627]: info: Cannot mount NAS Tp-share at system boot, trial number 1 ,retrying in 5 seconds Sep 24 13:58:24 rivo volumio[3627]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 24 13:58:24 rivo volumio[3627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 24 13:58:24 rivo volumio[3627]: info: VolumeController:: Volume=undefined Mute =false Sep 24 13:58:24 rivo volumio[3627]: info: CoreStateMachine::pushState Sep 24 13:58:24 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:24 rivo volumio[3627]: info: CoreStateMachine::updateTrackBlock Sep 24 13:58:24 rivo volumio[3627]: info: CorePlayQueue::getTrackBlock Sep 24 13:58:24 rivo volumio[3627]: info: CoreCommandRouter::volumioRetrievevolume Sep 24 13:58:25 rivo volumio[3627]: info: Reloading queue from file Sep 24 13:58:25 rivo volumio[3627]: info: Asound.conf file unchanged, so no further update is needed Sep 24 13:58:25 rivo volumio[3627]: info: Output device has changed, restarting MPD Sep 24 13:58:25 rivo sudo[3806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 24 13:58:25 rivo sudo[3806]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo volumio[3627]: info: Output device has changed, restarting Shairport Sync Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:25 rivo sudo[3806]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo sudo[3809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 24 13:58:25 rivo sudo[3809]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo systemd[1]: Stopping Music Player Daemon... Sep 24 13:58:25 rivo volumio[3627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 24 13:58:25 rivo volumio[3627]: info: ___________ START PLUGINS ___________ Sep 24 13:58:25 rivo volumio[3627]: info: ControllerMpd::onStart: Initializing MPD Sep 24 13:58:25 rivo volumio[3627]: info: Creating MPD Configuration file Sep 24 13:58:25 rivo sudo[3816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 24 13:58:25 rivo sudo[3816]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 24 13:58:25 rivo volumio[3627]: info: [1727179105300] CoreMusicLibrary::Adding element Media Servers Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:25 rivo sudo[3816]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo sudo[3818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 24 13:58:25 rivo sudo[3818]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 24 13:58:25 rivo volumio[3627]: info: Adding METAVOLUMIO REST API Endpoints Sep 24 13:58:25 rivo volumio[3627]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 24 13:58:25 rivo volumio[3627]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 24 13:58:25 rivo volumio[3627]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 24 13:58:25 rivo volumio[3627]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:25 rivo systemd[1]: mpd.service: Succeeded. Sep 24 13:58:25 rivo sudo[3822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Sep 24 13:58:25 rivo sudo[3822]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo systemd[1]: Stopped Music Player Daemon. Sep 24 13:58:25 rivo sudo[3822]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo systemd[1]: Starting Music Player Daemon... Sep 24 13:58:25 rivo volumio[3627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:25 rivo volumio[3627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 24 13:58:25 rivo volumio[3627]: info: [1727179105504] CoreMusicLibrary::Adding element Last_100 Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:25 rivo volumio[3627]: info: Streaming services startup Sep 24 13:58:25 rivo volumio[3627]: info: Starting Streaming Daemon Sep 24 13:58:25 rivo sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 24 13:58:25 rivo sudo[3825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 24 13:58:25 rivo sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3825]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3825]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo sudo[3838]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 24 13:58:25 rivo volumio[3627]: info: [1727179105590] CoreMusicLibrary::Adding element Webradio Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:58:25 rivo volumio[3627]: info: Initializing BBC Radios Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:58:25 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:25 rivo volumio[3627]: error: Hi Res Audio Failed Login: Missing Login Data Sep 24 13:58:25 rivo volumio[3627]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 24 13:58:25 rivo volumio[3627]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 24 13:58:25 rivo volumio[3627]: info: Initializing Serial Communication on port /dev/ttyS3 Sep 24 13:58:25 rivo volumio[3627]: info: Touch Event Listener Process Starting Sep 24 13:58:25 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Sep 24 13:58:25 rivo volumio[3627]: info: Refreshing QOBUZ token Sep 24 13:58:25 rivo sudo[3858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Sep 24 13:58:25 rivo sudo[3858]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3858]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo sudo[3861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Sep 24 13:58:25 rivo sudo[3861]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3861]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo sudo[3864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Sep 24 13:58:25 rivo sudo[3864]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3864]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo sudo[3867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Sep 24 13:58:25 rivo sudo[3867]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3867]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo sudo[3870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Sep 24 13:58:25 rivo sudo[3870]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3870]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo volumio[3627]: info: Apply VIM3L Onboard LEDs Settings Sep 24 13:58:25 rivo volumio[3627]: info: Turning On Onboard LEDs Sep 24 13:58:26 rivo sudo[3874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Sep 24 13:58:26 rivo sudo[3874]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo volumio[3627]: info: Starting CEC Event listener Sep 24 13:58:26 rivo sudo[3874]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 24 13:58:26 rivo volumio[3627]: info: [1727179106040] CoreMusicLibrary::Adding element LastFM Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:26 rivo volumio[3627]: Cannot find translation for source LastFM Sep 24 13:58:26 rivo volumio[3627]: info: [LastFM] scrobbler initiated! Sep 24 13:58:26 rivo volumio[3627]: info: [LastFM] extended logging: false Sep 24 13:58:26 rivo volumio[3627]: info: [LastFM] try scrobble stream/radio plays: true Sep 24 13:58:26 rivo volumio[3627]: info: [LastFM] Left init routine Sep 24 13:58:26 rivo volumio[3627]: info: [LastFM] Socket already connected: true Sep 24 13:58:26 rivo volumio[3627]: info: Volumio Calling Home Sep 24 13:58:26 rivo sudo[3878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Sep 24 13:58:26 rivo sudo[3878]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3878]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo volumio[3627]: info: Stopping AccessToken refresher cron Sep 24 13:58:26 rivo volumio[3627]: info: AccessToken refresher cron started Sep 24 13:58:26 rivo volumio[3627]: info: Adding TIDAL REST API Endpoints Sep 24 13:58:26 rivo volumio[3627]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 24 13:58:26 rivo volumio[3627]: info: CoreStateMachine::setRepeat false single undefined Sep 24 13:58:26 rivo volumio[3627]: info: CoreStateMachine::pushState Sep 24 13:58:26 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:26 rivo volumio[3627]: info: CoreStateMachine::setRandom true Sep 24 13:58:26 rivo volumio[3627]: info: CoreStateMachine::pushState Sep 24 13:58:26 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:26 rivo volumio[3627]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 24 13:58:26 rivo volumio[3627]: info: Serial port opened successfully Sep 24 13:58:26 rivo volumio[3627]: info: Sending serial start messages Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: MP1 GPIO: Signalled system ready via GPIO Sep 24 13:58:26 rivo volumio[3627]: info: Touch Event Listener Process Closed Sep 24 13:58:26 rivo volumio[3627]: error: Cannot start Volumio Streaming Daemon Sep 24 13:58:26 rivo volumio[3627]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 24 13:58:26 rivo volumio[3627]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 24 13:58:26 rivo volumio[3627]: info: Bluetooth name changed to Rivo Sep 24 13:58:26 rivo volumio[3627]: info: MPD Permissions set Sep 24 13:58:26 rivo volumio[3627]: info: MPD Permissions set Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Sep 24 13:58:26 rivo volumio[3627]: info: Setting Device Volume Override Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 24 13:58:26 rivo volumio[3627]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: Max Vol: 90 Vol Curve; logarithmic Vol Steps: 5 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 24 13:58:26 rivo volumio[3627]: info: Disabling external Volume Control Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreStateMachine::pushState Sep 24 13:58:26 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:26 rivo sudo[3890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 24 13:58:26 rivo volumio[3627]: info: VolumeController:: Volume=undefined Mute =false Sep 24 13:58:26 rivo sudo[3890]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo volumio[3627]: info: CoreStateMachine::pushState Sep 24 13:58:26 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Sep 24 13:58:26 rivo volumio[3627]: info: Setting Additional System Software info: Hardware Revision: 2.0 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Sep 24 13:58:26 rivo volumio[3627]: info: Setting HW Firmware info: undefined Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Sep 24 13:58:26 rivo volumio[3627]: info: Setting HW Version info: 2.0 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Sep 24 13:58:26 rivo volumio[3627]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.3 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Sep 24 13:58:26 rivo volumio[3627]: info: Setting HW Firmware info: 0.3.3 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Sep 24 13:58:26 rivo volumio[3627]: info: Setting HW Version info: 2.0 Sep 24 13:58:26 rivo volumio[3627]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 24 13:58:26 rivo volumio[3627]: info: MCU Signalled Headphone Mode Disabled Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 24 13:58:26 rivo volumio[3627]: info: MCU Signalled Sleep Mode Disabled Sep 24 13:58:26 rivo sudo[3890]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo volumio[3627]: info: Enabling Advanced system settings configuration Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Sep 24 13:58:26 rivo volumio[3627]: info: Additional UI Settings Added for plugin music_service/inputs Sep 24 13:58:26 rivo volumio[3627]: info: MCU Signalled Auto Boot Mode On Power Active Sep 24 13:58:26 rivo volumio[3627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 24 13:58:26 rivo sudo[3897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Sep 24 13:58:26 rivo sudo[3897]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3897]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo volumio[3627]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3627]: error: Serial API: Failed to decode command: MAXVOL, message: 90 Sep 24 13:58:27 rivo volumio[3627]: info: CoreStateMachine::pushState Sep 24 13:58:27 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:27 rivo volumio[3627]: info: Upmpdcli Daemon Started Sep 24 13:58:27 rivo volumio[3627]: info: VolumeController:: Volume=undefined Mute =false Sep 24 13:58:27 rivo volumio[3627]: info: CoreStateMachine::pushState Sep 24 13:58:27 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:27 rivo sudo[3913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Sep 24 13:58:27 rivo sudo[3913]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo volumio[3627]: info: Starting Shairport Sync Sep 24 13:58:27 rivo sudo[3913]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo volumio[3627]: info: Starting Shairport Sync Sep 24 13:58:27 rivo sudo[3916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 24 13:58:27 rivo sudo[3916]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo volumio[3627]: info: Starting Shairport Sync Sep 24 13:58:27 rivo sudo[3919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 24 13:58:27 rivo volumio[3627]: info: Adding Inputs via Serial API Sep 24 13:58:27 rivo sudo[3919]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 24 13:58:27 rivo systemd[1]: shairport-sync.service: Succeeded. Sep 24 13:58:27 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:27 rivo volumio[3627]: info: CoreStateMachine::pushState Sep 24 13:58:27 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:27 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:27 rivo sudo[3916]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 24 13:58:27 rivo sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 24 13:58:27 rivo volumio[3627]: info: Shairport-Sync Started Sep 24 13:58:27 rivo volumio[3627]: Error adding Membership: Error: addMembership EINVAL Sep 24 13:58:27 rivo systemd[1]: shairport-sync.service: Succeeded. Sep 24 13:58:27 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::servicePushState Sep 24 13:58:27 rivo volumio[3627]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3627]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received inputs Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 24 13:58:27 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:27 rivo volumio[3627]: Cannot find translation for source LastFM Sep 24 13:58:27 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:27 rivo sudo[3919]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo sudo[3921]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo volumio[3627]: info: Shairport-Sync Started Sep 24 13:58:27 rivo volumio[3627]: info: Shairport-Sync Started Sep 24 13:58:27 rivo mpd[3846]: Sep 24 13:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 24 13:58:27 rivo systemd[1]: Started Music Player Daemon. Sep 24 13:58:27 rivo sudo[3818]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo sudo[3809]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo volumio[3627]: error: MPD error: The expression evaluated to a falsy value: Sep 24 13:58:28 rivo volumio[3627]: assert.ok(self.idling) Sep 24 13:58:28 rivo volumio[3627]: error: The expression evaluated to a falsy value: Sep 24 13:58:28 rivo volumio[3627]: assert.ok(self.idling) Sep 24 13:58:28 rivo volumio[3627]: error: updateQueue error: null Sep 24 13:58:28 rivo volumio[3627]: info: MPD running with PID3846 Sep 24 13:58:28 rivo volumio[3627]: ,establishing connection Sep 24 13:58:28 rivo volumio[3627]: error: updateQueue error: null Sep 24 13:58:28 rivo sudo[3935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 24 13:58:28 rivo sudo[3935]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:28 rivo sudo[3935]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo volumio[3627]: info: Volumio BT Module successfully started Sep 24 13:58:28 rivo volumio[3627]: info: Turning Off Onboard LEDs Sep 24 13:58:28 rivo sudo[3939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Sep 24 13:58:28 rivo sudo[3939]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:28 rivo sudo[3939]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Sep 24 13:58:28 rivo sudo[3942]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:28 rivo sudo[3942]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo volumio[3627]: info: Onboard LEDs initialized Sep 24 13:58:29 rivo volumio-remote-updater[2844]: [2024-09-24 13:58:29] [connect] Successful connection Sep 24 13:58:29 rivo volumio-remote-updater[2844]: [2024-09-24 13:58:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727179109 101 Sep 24 13:58:29 rivo volumio[3627]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Sep 24 13:58:29 rivo sudo[3945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=TubeDAC,password=spliTT67#,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.219/To-share/Lossless /mnt/NAS/Tp-share Sep 24 13:58:29 rivo sudo[3945]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:30 rivo ntpd[3065]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 24 13:58:33 rivo volumio[3627]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 24 13:58:36 rivo sudo[3945]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:36 rivo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Sep 24 13:58:36 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -113 Sep 24 13:58:36 rivo volumio[3627]: info: Cannot mount NAS Tp-share at system boot, trial number 2 ,retrying in 5 seconds Sep 24 13:58:36 rivo volumio[3627]: info: Checking for updated MCU Firmware Sep 24 13:58:36 rivo volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:58:36 rivo volumio[3627]: info: Firware on device is on latest version, no need to update Sep 24 13:58:41 rivo sudo[3951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=TubeDAC,password=spliTT67#,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.219/To-share/Lossless /mnt/NAS/Tp-share Sep 24 13:58:41 rivo sudo[3951]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:42 rivo dhcpcd[3268]: eth0: offered 192.168.0.2 from 192.168.0.1 Sep 24 13:58:42 rivo dhcpcd[3247]: eth0: offered 192.168.0.2 from 192.168.0.1 Sep 24 13:58:42 rivo dhcpcd[3268]: eth0: probing address 192.168.0.2/24 Sep 24 13:58:42 rivo dhcpcd[3247]: eth0: probing address 192.168.0.2/24 Sep 24 13:58:46 rivo volumio[3627]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 13:58:46 rivo volumio[3627]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Sep 24 13:58:46 rivo volumio[3627]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Sep 24 13:58:46 rivo volumio[3627]: errno: -3007, Sep 24 13:58:46 rivo volumio[3627]: code: 'ENOTFOUND', Sep 24 13:58:46 rivo volumio[3627]: syscall: 'getaddrinfo', Sep 24 13:58:46 rivo volumio[3627]: hostname: 'ws.audioscrobbler.com' Sep 24 13:58:46 rivo volumio[3627]: } Sep 24 13:58:46 rivo volumio[3627]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 13:58:46 rivo sudo[3965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-24 13:57 Sep 24 13:58:46 rivo sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:47:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e08c3e210a64867536255c2503fb09"