-- Logs begin at Tue 2024-09-24 13:56:20 CEST, end at Tue 2024-09-24 13:58:47 CEST. -- Sep 24 13:57:00 rivo volumio[3111]: info: Cannot mount NAS Tp-share at system boot, trial number 2 ,retrying in 5 seconds Sep 24 13:57:00 rivo volumio[3111]: info: Checking for updated MCU Firmware Sep 24 13:57:00 rivo volumio[3111]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:57:00 rivo volumio[3111]: info: Firware on device is on latest version, no need to update Sep 24 13:57:01 rivo volumio[3460]: .............................................................................................++++ Sep 24 13:57:02 rivo volumio[3460]: ............++++ Sep 24 13:57:02 rivo volumio[3460]: e is 65537 (0x010001) Sep 24 13:57:02 rivo volumio[3460]: writing RSA key Sep 24 13:57:05 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Sep 24 13:57:05 rivo sudo[3574]: 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[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:57:08 rivo kernel: fb: mem_free_work, free memory: addr:800000 Sep 24 13:57:09 rivo volumio[3111]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 13:57:09 rivo volumio[3111]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Sep 24 13:57:09 rivo volumio[3111]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Sep 24 13:57:09 rivo volumio[3111]: errno: -3007, Sep 24 13:57:09 rivo volumio[3111]: code: 'ENOTFOUND', Sep 24 13:57:09 rivo volumio[3111]: syscall: 'getaddrinfo', Sep 24 13:57:09 rivo volumio[3111]: hostname: 'ws.audioscrobbler.com' Sep 24 13:57:09 rivo volumio[3111]: } Sep 24 13:57:09 rivo volumio[3111]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 13:57:10 rivo sudo[3588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-24 13:56 Sep 24 13:57:10 rivo sudo[3588]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:57:10 rivo sudo[3588]: pam_unix(sudo:session): session closed for user root Sep 24 13:57:11 rivo sudo[3574]: 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:27 rivo volumio-remote-updater[2835]: [2024-09-24 13:57:27] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 24 13:57:32 rivo volumio-remote-updater[2835]: [2024-09-24 13:57:32] [connect] Successful connection Sep 24 13:57:44 rivo volumio-remote-updater[2835]: [2024-09-24 13:57:44] [connect] Successful connection Sep 24 13:57:53 rivo ntpd[3075]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 24 13:57:59 rivo volumio-remote-updater[2835]: [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[3537]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:10 rivo systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 24 13:58:10 rivo systemd[1]: Started dynamicswap service. Sep 24 13:58:10 rivo systemd[1]: dynamicswap.service: Succeeded. Sep 24 13:58:10 rivo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 24 13:58:10 rivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 24 13:58:10 rivo systemd[1]: Stopped Volumio Backend Module. Sep 24 13:58:10 rivo systemd[1]: Started Volumio Backend Module. Sep 24 13:58:10 rivo systemd[1]: Started dynamicswap service. Sep 24 13:58:10 rivo systemd[1]: dynamicswap.service: Succeeded. Sep 24 13:58:12 rivo volumio[3650]: info: ------------------------------------------- Sep 24 13:58:12 rivo volumio[3650]: info: ----- Volumio3 ---- Sep 24 13:58:12 rivo volumio[3650]: info: ------------------------------------------- Sep 24 13:58:12 rivo volumio[3650]: info: ----- System startup ---- Sep 24 13:58:12 rivo volumio[3650]: info: ------------------------------------------- Sep 24 13:58:13 rivo volumio[3650]: info: MYVOLUMIO Environment detected Sep 24 13:58:13 rivo volumio[3650]: info: Plugin folders cleanup Sep 24 13:58:13 rivo volumio[3650]: info: Scanning into folder /volumio/app/plugins/ Sep 24 13:58:13 rivo volumio[3650]: info: Scanning category audio_interface Sep 24 13:58:13 rivo volumio[3650]: info: Scanning category miscellanea Sep 24 13:58:13 rivo volumio[3650]: info: Scanning category music_service Sep 24 13:58:13 rivo volumio[3650]: info: Scanning category plugins.json Sep 24 13:58:13 rivo volumio[3650]: info: Scanning category system_controller Sep 24 13:58:13 rivo volumio[3650]: info: Scanning category user_interface Sep 24 13:58:13 rivo volumio[3650]: info: Scanning into folder /data/plugins/ Sep 24 13:58:13 rivo volumio[3650]: info: Scanning category music_service Sep 24 13:58:13 rivo volumio[3650]: info: Scanning category user_interface Sep 24 13:58:13 rivo volumio[3650]: info: Plugin folders cleanup completed Sep 24 13:58:13 rivo volumio[3650]: info: ------------------------------------------- Sep 24 13:58:13 rivo volumio[3650]: info: ----- Core plugins startup ---- Sep 24 13:58:13 rivo volumio[3650]: info: ------------------------------------------- Sep 24 13:58:13 rivo volumio[3650]: info: Loading plugins from folder /volumio/app/plugins/ Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin bluetooth to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin multiroom to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin upnp to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin metavolumio to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin cd_controller to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin raat to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 24 13:58:13 rivo volumio[3650]: info: Loading plugins from folder /data/plugins/ Sep 24 13:58:13 rivo volumio[3650]: info: Loading plugin "system"... Sep 24 13:58:13 rivo ntpd[3075]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 24 13:58:13 rivo volumio[3650]: info: Loading plugin "appearance"... Sep 24 13:58:14 rivo volumio[3650]: info: Loading plugin "network"... Sep 24 13:58:14 rivo volumio[3650]: info: Refreshing Cached IP Addresses Sep 24 13:58:14 rivo volumio[3650]: info: Loading plugin "services"... Sep 24 13:58:14 rivo sudo[3678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 24 13:58:14 rivo sudo[3678]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo volumio[3650]: info: Loading plugin "alsa_controller"... Sep 24 13:58:14 rivo sudo[3680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 24 13:58:14 rivo sudo[3680]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo sudo[3678]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:14 rivo sudo[3680]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:14 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:58:14 rivo volumio[3650]: info: Loading plugin "wizard"... Sep 24 13:58:14 rivo volumio[3650]: info: Loading plugin "networkfs"... Sep 24 13:58:14 rivo volumio[3650]: info: Starting Udev Watcher for removable devices Sep 24 13:58:14 rivo sudo[3690]: 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[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo sudo[3692]: 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[3692]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:14 rivo volumio-remote-updater[2835]: [2024-09-24 13:58:14] [connect] Successful connection Sep 24 13:58:14 rivo volumio[3650]: info: Mounting Device issd Sep 24 13:58:14 rivo volumio[3650]: /bin/mkdir: cannot create directory ‘/mnt/INTERNAL/’: File exists Sep 24 13:58:14 rivo volumio[3650]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/INTERNAL/" Sep 24 13:58:14 rivo volumio[3650]: /bin/mkdir: cannot create directory ‘/mnt/INTERNAL/’: File exists Sep 24 13:58:14 rivo sudo[3704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/mmcblk1p1 /mnt/INTERNAL/ -o noatime Sep 24 13:58:14 rivo sudo[3704]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:15 rivo sudo[3704]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:15 rivo volumio[3650]: fuse: bad mount point `/mnt/INTERNAL/': Transport endpoint is not connected Sep 24 13:58:15 rivo volumio[3650]: error: Failed to mount issd: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/mmcblk1p1" "/mnt/INTERNAL/" -o noatime Sep 24 13:58:15 rivo volumio[3650]: fuse: bad mount point `/mnt/INTERNAL/': Transport endpoint is not connected Sep 24 13:58:15 rivo volumio[3650]: info: Ignoring mount for partition: BOOT Sep 24 13:58:15 rivo volumio[3650]: info: Ignoring mount for partition: volumio Sep 24 13:58:15 rivo volumio[3650]: info: Ignoring mount for partition: volumio_data Sep 24 13:58:15 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 24 13:58:15 rivo volumio[3650]: info: Loading plugin "volumio_command_line_client"... Sep 24 13:58:15 rivo volumio[3650]: info: Loading plugin "upnp"... Sep 24 13:58:15 rivo volumio[3650]: info: [1727179095078] Starting Upmpd Daemon Sep 24 13:58:15 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 24 13:58:15 rivo volumio[3650]: info: Loading plugin "my_music"... Sep 24 13:58:15 rivo volumio[3650]: info: Loading plugin "mpd"... Sep 24 13:58:15 rivo volumio[3650]: info: Loading plugin "upnp_browser"... Sep 24 13:58:15 rivo volumio[3650]: info: Loading plugin "bluetooth"... Sep 24 13:58:16 rivo volumio[3650]: info: [1727179096005] Starting BluetoothController Sep 24 13:58:16 rivo volumio[3650]: info: Loading plugin "alarm-clock"... Sep 24 13:58:16 rivo volumio[3650]: info: Loading plugin "manifestui"... Sep 24 13:58:16 rivo volumio[3650]: info: Loading plugin "metavolumio"... Sep 24 13:58:17 rivo volumio[3650]: info: Loading plugin "airplay_emulation"... Sep 24 13:58:17 rivo volumio[3650]: info: Starting Shairport Sync Sep 24 13:58:17 rivo volumio[3650]: info: Plugin cd_controller is not enabled Sep 24 13:58:17 rivo volumio[3650]: info: Loading plugin "last_100"... Sep 24 13:58:17 rivo volumio[3650]: info: Plugin raat is not enabled Sep 24 13:58:17 rivo volumio[3650]: info: Loading plugin "streaming_services"... Sep 24 13:58:18 rivo volumio[3650]: info: Starting Streaming Service Transparent Proxy Sep 24 13:58:18 rivo volumio[3650]: info: Plugin tidalconnect is not enabled Sep 24 13:58:18 rivo volumio[3650]: info: Loading plugin "webradio"... Sep 24 13:58:18 rivo volumio[3650]: info: Loading plugin "i2s_dacs"... Sep 24 13:58:18 rivo volumio[3650]: info: I2S DAC not set, start Auto-detection Sep 24 13:58:18 rivo volumio[3650]: info: Loading plugin "volumiodiscovery"... Sep 24 13:58:18 rivo volumio[3650]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 24 13:58:18 rivo volumio[3650]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 24 13:58:18 rivo node[3650]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 24 13:58:18 rivo volumio[3650]: *** WARNING *** For more information see Sep 24 13:58:18 rivo volumio[3650]: *** 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[3650]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 24 13:58:18 rivo volumio[3650]: *** WARNING *** For more information see Sep 24 13:58:18 rivo node[3650]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 24 13:58:18 rivo node[3650]: *** WARNING *** For more information see Sep 24 13:58:18 rivo node[3650]: *** 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[3650]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 24 13:58:18 rivo node[3650]: *** WARNING *** For more information see Sep 24 13:58:18 rivo volumio[3650]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 24 13:58:18 rivo volumio[3650]: info: Discovery: Started advertising with name: Rivo Sep 24 13:58:18 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 24 13:58:18 rivo volumio[3650]: info: Plugin calmradio is not enabled Sep 24 13:58:18 rivo volumio[3650]: info: Plugin multiroom is not enabled Sep 24 13:58:18 rivo volumio[3650]: info: Loading plugin "outputs"... Sep 24 13:58:18 rivo volumio[3650]: info: Loading plugin "albumart"... Sep 24 13:58:18 rivo volumio[3650]: info: Plugin example_plugin is not enabled Sep 24 13:58:18 rivo volumio[3650]: info: Loading plugin "hi_res_audio"... Sep 24 13:58:19 rivo volumio[3650]: Forking 3 albumart workers Sep 24 13:58:20 rivo volumio[3650]: info: Applying required configuration parameters for plugin hi_res_audio Sep 24 13:58:20 rivo volumio[3650]: info: Loading plugin "inputs"... Sep 24 13:58:20 rivo volumio[3650]: STREAMING PROXY: Starting server on port 3245 Sep 24 13:58:20 rivo volumio[3650]: Node JS runtime: 14 Sep 24 13:58:20 rivo volumio[3650]: Starting albumart workers Sep 24 13:58:20 rivo volumio[3650]: Starting albumart workers Sep 24 13:58:20 rivo volumio[3650]: Starting albumart workers Sep 24 13:58:20 rivo volumio[3650]: 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[3692]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:22 rivo volumio[3650]: info: Plugin smart_inputs is not enabled Sep 24 13:58:22 rivo volumio[3650]: info: Loading plugin "tidal"... Sep 24 13:58:23 rivo volumio[3650]: info: Loading plugin "rivocontrol"... Sep 24 13:58:23 rivo volumio[3650]: info: Adding this device properties Sep 24 13:58:23 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Sep 24 13:58:23 rivo volumio[3650]: info: Setting Additional Device Volumio Properties: [object Object] Sep 24 13:58:23 rivo volumio[3650]: info: Loading plugin "updater_comm"... Sep 24 13:58:23 rivo volumio[3650]: info: Loading plugin "cec_controller"... Sep 24 13:58:24 rivo volumio[3650]: info: Applying required configuration parameters for plugin cec_controller Sep 24 13:58:24 rivo volumio[3650]: info: Plugin mpdemulation is not enabled Sep 24 13:58:24 rivo volumio[3650]: info: Loading plugin "rest_api"... Sep 24 13:58:24 rivo volumio[3650]: info: Loading plugin "websocket"... Sep 24 13:58:24 rivo volumio[3650]: info: Starting Socket.io Server version 2.3.0 Sep 24 13:58:24 rivo volumio[3650]: info: Plugin 80s80s is not enabled Sep 24 13:58:24 rivo volumio[3650]: info: Plugin motherearthradio is not enabled Sep 24 13:58:24 rivo volumio[3650]: info: Plugin radio_paradise is not enabled Sep 24 13:58:24 rivo volumio[3650]: info: Plugin Systeminfo is not enabled Sep 24 13:58:24 rivo volumio[3650]: info: Loading plugin "lastfm"... Sep 24 13:58:24 rivo volumio[3650]: info: Plugin randomizer is not enabled Sep 24 13:58:24 rivo volumio[3650]: info: Loading i18n strings for locale en Sep 24 13:58:24 rivo volumio[3650]: Updating browse sources language Sep 24 13:58:24 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:24 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::initPlayerControls Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: Express server listening on port 3000 Sep 24 13:58:25 rivo volumio[3650]: [Metrics] WebUI: 13s 211.80ms Sep 24 13:58:25 rivo volumio[3650]: info: CoreStateMachine::resetVolumioState Sep 24 13:58:25 rivo volumio[3650]: info: CoreStateMachine::getcurrentVolume Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::volumioRetrievevolume Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:25 rivo sudo[3816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 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 sudo[3816]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo volumio[3650]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 24 13:58:25 rivo volumio[3650]: info: Completed loading Core Plugins Sep 24 13:58:25 rivo sudo[3818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 24 13:58:25 rivo volumio[3650]: info: Preparing to generate the ALSA configuration file 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 sudo[3818]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo volumio[3650]: info: Cannot mount NAS Tp-share at system boot, trial number 1 ,retrying in 5 seconds Sep 24 13:58:25 rivo volumio[3650]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 24 13:58:25 rivo volumio[3650]: 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:25 rivo volumio[3650]: info: VolumeController:: Volume=89 Mute =false Sep 24 13:58:25 rivo volumio[3650]: info: CoreStateMachine::pushState Sep 24 13:58:25 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:25 rivo volumio[3650]: info: CoreStateMachine::updateTrackBlock Sep 24 13:58:25 rivo volumio[3650]: info: CorePlayQueue::getTrackBlock Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::volumioRetrievevolume Sep 24 13:58:25 rivo volumio[3650]: info: Reloading queue from file Sep 24 13:58:25 rivo volumio[3650]: info: Asound.conf file unchanged, so no further update is needed Sep 24 13:58:25 rivo volumio[3650]: info: Output device has changed, restarting MPD Sep 24 13:58:25 rivo sudo[3831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 24 13:58:25 rivo sudo[3831]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3831]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo volumio[3650]: info: Output device has changed, restarting Shairport Sync Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:25 rivo sudo[3834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 24 13:58:25 rivo sudo[3834]: 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[3650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 24 13:58:25 rivo volumio[3650]: info: ___________ START PLUGINS ___________ Sep 24 13:58:25 rivo volumio[3650]: info: ControllerMpd::onStart: Initializing MPD Sep 24 13:58:25 rivo volumio[3650]: info: Creating MPD Configuration file Sep 24 13:58:25 rivo sudo[3840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 24 13:58:25 rivo sudo[3840]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 24 13:58:25 rivo volumio[3650]: info: [1727179105782] CoreMusicLibrary::Adding element Media Servers Sep 24 13:58:25 rivo sudo[3840]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:25 rivo sudo[3843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 24 13:58:25 rivo sudo[3843]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 24 13:58:25 rivo volumio[3650]: info: Adding METAVOLUMIO REST API Endpoints Sep 24 13:58:25 rivo volumio[3650]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 24 13:58:25 rivo volumio[3650]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 24 13:58:25 rivo volumio[3650]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 24 13:58:25 rivo volumio[3650]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:25 rivo sudo[3846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Sep 24 13:58:25 rivo sudo[3846]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:25 rivo sudo[3846]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:25 rivo systemd[1]: mpd.service: Succeeded. Sep 24 13:58:25 rivo systemd[1]: Stopped Music Player Daemon. Sep 24 13:58:25 rivo systemd[1]: Starting Music Player Daemon... Sep 24 13:58:25 rivo volumio[3650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:25 rivo volumio[3650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 24 13:58:25 rivo volumio[3650]: info: [1727179105982] CoreMusicLibrary::Adding element Last_100 Sep 24 13:58:25 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:25 rivo volumio[3650]: info: Streaming services startup Sep 24 13:58:25 rivo volumio[3650]: info: Starting Streaming Daemon Sep 24 13:58:26 rivo sudo[3863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 24 13:58:26 rivo sudo[3863]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 24 13:58:26 rivo sudo[3851]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3863]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo sudo[3851]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 24 13:58:26 rivo volumio[3650]: info: [1727179106089] CoreMusicLibrary::Adding element Webradio Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:58:26 rivo volumio[3650]: info: Initializing BBC Radios Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:26 rivo volumio[3650]: error: Hi Res Audio Failed Login: Missing Login Data Sep 24 13:58:26 rivo volumio[3650]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 24 13:58:26 rivo volumio[3650]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 24 13:58:26 rivo volumio[3650]: info: Initializing Serial Communication on port /dev/ttyS3 Sep 24 13:58:26 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Sep 24 13:58:26 rivo volumio[3650]: info: Touch Event Listener Process Starting Sep 24 13:58:26 rivo volumio[3650]: info: Refreshing QOBUZ token Sep 24 13:58:26 rivo sudo[3883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Sep 24 13:58:26 rivo sudo[3883]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3883]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo sudo[3886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Sep 24 13:58:26 rivo sudo[3886]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3886]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo sudo[3889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Sep 24 13:58:26 rivo sudo[3889]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3889]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo sudo[3892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Sep 24 13:58:26 rivo sudo[3892]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3892]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo sudo[3895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Sep 24 13:58:26 rivo sudo[3895]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3895]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo volumio[3650]: info: Apply VIM3L Onboard LEDs Settings Sep 24 13:58:26 rivo volumio[3650]: info: Turning On Onboard LEDs Sep 24 13:58:26 rivo sudo[3899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Sep 24 13:58:26 rivo sudo[3899]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo volumio[3650]: info: Starting CEC Event listener Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 24 13:58:26 rivo volumio[3650]: info: [1727179106536] CoreMusicLibrary::Adding element LastFM Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:26 rivo volumio[3650]: Cannot find translation for source LastFM Sep 24 13:58:26 rivo sudo[3899]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo volumio[3650]: info: [LastFM] scrobbler initiated! Sep 24 13:58:26 rivo volumio[3650]: info: [LastFM] extended logging: false Sep 24 13:58:26 rivo volumio[3650]: info: [LastFM] try scrobble stream/radio plays: true Sep 24 13:58:26 rivo volumio[3650]: info: [LastFM] Left init routine Sep 24 13:58:26 rivo volumio[3650]: info: [LastFM] Socket already connected: true Sep 24 13:58:26 rivo volumio[3650]: info: Volumio Calling Home Sep 24 13:58:26 rivo sudo[3903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Sep 24 13:58:26 rivo sudo[3903]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:26 rivo sudo[3903]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:26 rivo volumio[3650]: info: Stopping AccessToken refresher cron Sep 24 13:58:26 rivo volumio[3650]: info: AccessToken refresher cron started Sep 24 13:58:26 rivo volumio[3650]: info: Adding TIDAL REST API Endpoints Sep 24 13:58:26 rivo volumio[3650]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 24 13:58:26 rivo volumio[3650]: info: CoreStateMachine::setRepeat false single undefined Sep 24 13:58:26 rivo volumio[3650]: info: CoreStateMachine::pushState Sep 24 13:58:26 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:26 rivo volumio[3650]: info: CoreStateMachine::setRandom false Sep 24 13:58:26 rivo volumio[3650]: info: CoreStateMachine::pushState Sep 24 13:58:26 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:26 rivo volumio[3650]: info: Serial port opened successfully Sep 24 13:58:26 rivo volumio[3650]: info: Sending serial start messages Sep 24 13:58:26 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:26 rivo volumio[3650]: info: MP1 GPIO: Signalled system ready via GPIO Sep 24 13:58:27 rivo volumio[3650]: info: Touch Event Listener Process Closed Sep 24 13:58:27 rivo volumio[3650]: error: Cannot start Volumio Streaming Daemon Sep 24 13:58:27 rivo volumio[3650]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 24 13:58:27 rivo volumio[3650]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 24 13:58:27 rivo volumio[3650]: info: Bluetooth name changed to Rivo Sep 24 13:58:27 rivo volumio[3650]: info: MPD Permissions set Sep 24 13:58:27 rivo volumio[3650]: info: MPD Permissions set Sep 24 13:58:27 rivo sudo[3912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 24 13:58:27 rivo sudo[3912]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo volumio[3650]: info: VolumeController:: Volume=89 Mute =false Sep 24 13:58:27 rivo volumio[3650]: info: CoreStateMachine::pushState Sep 24 13:58:27 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:27 rivo sudo[3912]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 24 13:58:27 rivo volumio[3650]: info: Discovery: Getting this device information Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::volumioGetState Sep 24 13:58:27 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 24 13:58:27 rivo volumio[3650]: 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:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Sep 24 13:58:27 rivo volumio[3650]: info: Setting Device Volume Override Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 24 13:58:27 rivo volumio[3650]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 Output Max Vol: 90 Vol Curve; logarithmic Vol Steps: 5 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 24 13:58:27 rivo volumio[3650]: info: Disabling external Volume Control Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 24 13:58:27 rivo volumio[3650]: info: CoreStateMachine::pushState Sep 24 13:58:27 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Sep 24 13:58:27 rivo volumio[3650]: info: Setting Additional System Software info: Hardware Revision: 2.0 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Sep 24 13:58:27 rivo volumio[3650]: info: Setting HW Firmware info: undefined Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Sep 24 13:58:27 rivo volumio[3650]: info: Setting HW Version info: 2.0 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Sep 24 13:58:27 rivo volumio[3650]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.3 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Sep 24 13:58:27 rivo volumio[3650]: info: Setting HW Firmware info: 0.3.3 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Sep 24 13:58:27 rivo volumio[3650]: info: Setting HW Version info: 2.0 Sep 24 13:58:27 rivo volumio[3650]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 24 13:58:27 rivo volumio[3650]: info: MCU Signalled Headphone Mode Disabled Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 24 13:58:27 rivo volumio[3650]: info: MCU Signalled Sleep Mode Disabled Sep 24 13:58:27 rivo volumio[3650]: info: Enabling Advanced system settings configuration Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Sep 24 13:58:27 rivo volumio[3650]: info: Additional UI Settings Added for plugin music_service/inputs Sep 24 13:58:27 rivo volumio[3650]: info: MCU Signalled Auto Boot Mode On Power Active Sep 24 13:58:27 rivo sudo[3933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Sep 24 13:58:27 rivo sudo[3933]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo sudo[3933]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo volumio[3650]: info: Upmpdcli Daemon Started Sep 24 13:58:27 rivo volumio[3650]: error: Serial API: Failed to decode command: MAXVOL, message: 90 Sep 24 13:58:27 rivo volumio[3650]: info: CoreStateMachine::pushState Sep 24 13:58:27 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:27 rivo volumio[3650]: verbose: New Socket.io Connection to 169.254.4.239:3000 from 172.20.10.10 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Sep 24 13:58:27 rivo sudo[3936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Sep 24 13:58:27 rivo sudo[3936]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo volumio[3650]: info: Starting Shairport Sync Sep 24 13:58:27 rivo sudo[3936]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo volumio[3650]: info: Starting Shairport Sync Sep 24 13:58:27 rivo sudo[3940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 24 13:58:27 rivo sudo[3940]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo volumio[3650]: info: Starting Shairport Sync 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 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:27 rivo sudo[3944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 24 13:58:27 rivo sudo[3944]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo sudo[3940]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:27 rivo volumio[3650]: info: Adding Inputs via Serial API Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::volumioGetState Sep 24 13:58:27 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 24 13:58:27 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 24 13:58:27 rivo sudo[3947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 24 13:58:27 rivo sudo[3947]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:27 rivo volumio[3650]: info: Shairport-Sync Started Sep 24 13:58:27 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 24 13:58:27 rivo volumio[3650]: Error adding Membership: Error: addMembership EINVAL Sep 24 13:58:28 rivo volumio[3650]: info: VolumeController:: Volume=89 Mute =false Sep 24 13:58:28 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 24 13:58:28 rivo systemd[1]: shairport-sync.service: Succeeded. Sep 24 13:58:28 rivo volumio[3650]: info: CoreStateMachine::pushState Sep 24 13:58:28 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:28 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:28 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:28 rivo volumio[3650]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:28 rivo volumio[3650]: info: CoreCommandRouter::servicePushState Sep 24 13:58:28 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:28 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:28 rivo volumio[3650]: 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:28 rivo sudo[3944]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo volumio[3650]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 24 13:58:28 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 24 13:58:28 rivo volumio[3650]: Cannot find translation for source LastFM Sep 24 13:58:28 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 24 13:58:28 rivo volumio[3650]: info: Shairport-Sync Started Sep 24 13:58:28 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 24 13:58:28 rivo systemd[1]: shairport-sync.service: Succeeded. Sep 24 13:58:28 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:28 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 24 13:58:28 rivo sudo[3947]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo volumio[3650]: info: Shairport-Sync Started Sep 24 13:58:28 rivo volumio[3650]: info: CoreStateMachine::pushState Sep 24 13:58:28 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:28 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 24 13:58:28 rivo volumio[3650]: info: CoreCommandRouter::volumioPushState Sep 24 13:58:28 rivo mpd[3871]: Sep 24 13:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 24 13:58:28 rivo systemd[1]: Started Music Player Daemon. Sep 24 13:58:28 rivo sudo[3843]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo sudo[3834]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo volumio[3650]: error: MPD error: The expression evaluated to a falsy value: Sep 24 13:58:28 rivo volumio[3650]: assert.ok(self.idling) Sep 24 13:58:28 rivo volumio[3650]: error: The expression evaluated to a falsy value: Sep 24 13:58:28 rivo volumio[3650]: assert.ok(self.idling) Sep 24 13:58:28 rivo volumio[3650]: error: updateQueue error: null Sep 24 13:58:28 rivo volumio[3650]: info: MPD running with PID3871 Sep 24 13:58:28 rivo volumio[3650]: ,establishing connection Sep 24 13:58:28 rivo volumio[3650]: error: updateQueue error: null Sep 24 13:58:28 rivo sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 24 13:58:28 rivo sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:28 rivo sudo[3960]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:28 rivo volumio[3650]: info: Volumio BT Module successfully started Sep 24 13:58:29 rivo volumio[3650]: info: Turning Off Onboard LEDs Sep 24 13:58:29 rivo sudo[3964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Sep 24 13:58:29 rivo sudo[3964]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:29 rivo sudo[3964]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:29 rivo sudo[3967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Sep 24 13:58:29 rivo sudo[3967]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:29 rivo sudo[3967]: pam_unix(sudo:session): session closed for user root Sep 24 13:58:29 rivo volumio[3650]: info: Onboard LEDs initialized Sep 24 13:58:29 rivo volumio-remote-updater[2835]: [2024-09-24 13:58:29] [connect] Successful connection Sep 24 13:58:29 rivo volumio-remote-updater[2835]: [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[3650]: 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: 4 Sep 24 13:58:30 rivo sudo[3970]: 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:30 rivo sudo[3970]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:32 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 24 13:58:32 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 24 13:58:32 rivo volumio[3650]: info: Discovery: Getting this device information Sep 24 13:58:32 rivo volumio[3650]: info: CoreCommandRouter::volumioGetState Sep 24 13:58:32 rivo volumio[3650]: info: CorePlayQueue::getTrack 0 Sep 24 13:58:32 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 24 13:58:33 rivo ntpd[3075]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 24 13:58:34 rivo volumio[3650]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 24 13:58:36 rivo sudo[3970]: 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[3650]: info: Cannot mount NAS Tp-share at system boot, trial number 2 ,retrying in 5 seconds Sep 24 13:58:36 rivo volumio[3650]: info: Checking for updated MCU Firmware Sep 24 13:58:36 rivo volumio[3650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 24 13:58:36 rivo volumio[3650]: info: Firware on device is on latest version, no need to update Sep 24 13:58:38 rivo dhcpcd[3275]: eth0: offered 172.20.10.9 from 172.20.10.1 `Krokerud' Sep 24 13:58:38 rivo dhcpcd[3275]: eth0: probing address 172.20.10.9/28 Sep 24 13:58:38 rivo dhcpcd[3265]: eth0: offered 172.20.10.9 from 172.20.10.1 `Krokerud' Sep 24 13:58:38 rivo dhcpcd[3265]: eth0: probing address 172.20.10.9/28 Sep 24 13:58:41 rivo sudo[3975]: 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[3975]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 24 13:58:43 rivo dhcpcd[3275]: eth0: leased 172.20.10.9 for 3600 seconds Sep 24 13:58:43 rivo dhcpcd[3275]: eth0: adding route to 172.20.10.0/28 Sep 24 13:58:43 rivo dhcpcd[3275]: eth0: changing default route via 172.20.10.1 Sep 24 13:58:43 rivo avahi-daemon[2866]: Registering new address record for 172.20.10.9 on eth0.IPv4. Sep 24 13:58:43 rivo dhcpcd[3265]: eth0: deleted default route Sep 24 13:58:43 rivo ntpd[3075]: ntpd exiting on signal 15 (Terminated) Sep 24 13:58:43 rivo systemd[1]: Stopping Network Time Service... Sep 24 13:58:43 rivo volumio[3650]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 24 13:58:43 rivo systemd[1]: ntp.service: Succeeded. Sep 24 13:58:43 rivo systemd[1]: Stopped Network Time Service. Sep 24 13:58:43 rivo volumio[3650]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 24 13:58:43 rivo systemd[1]: Starting Network Time Service... Sep 24 13:58:43 rivo volumio[3650]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 24 13:58:43 rivo ntpd[4012]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 24 13:58:43 rivo ntpd[4012]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 24 13:58:43 rivo systemd[1]: Started Network Time Service. Sep 24 13:58:43 rivo ntpd[4024]: proto: precision = 1.208 usec (-20) Sep 24 13:58:43 rivo avahi-daemon[2866]: Withdrawing address record for 169.254.4.239 on eth0. Sep 24 13:58:43 rivo avahi-daemon[2866]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.4.239. Sep 24 13:58:43 rivo ntpd[4024]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 24 13:58:43 rivo ntpd[4024]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 24 13:58:43 rivo ntpd[4024]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 637 days ago Sep 24 13:58:43 rivo ntpd[4024]: Listen and drop on 0 v6wildcard [::]:123 Sep 24 13:58:43 rivo avahi-daemon[2866]: Joining mDNS multicast group on interface eth0.IPv4 with address 172.20.10.9. Sep 24 13:58:43 rivo ntpd[4024]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 24 13:58:43 rivo dhcpcd[3275]: eth0: deleting route to 169.254.0.0/16 Sep 24 13:58:43 rivo ntpd[4024]: Listen normally on 2 lo 127.0.0.1:123 Sep 24 13:58:43 rivo ntpd[4024]: Listen normally on 3 eth0 172.20.10.9:123 Sep 24 13:58:43 rivo ntpd[4024]: Listening on routing socket on fd #20 for interface updates Sep 24 13:58:43 rivo ntpd[4024]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 24 13:58:43 rivo ntpd[4024]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 24 13:58:44 rivo dhcpcd[3265]: eth0: leased 172.20.10.9 for 3600 seconds Sep 24 13:58:44 rivo dhcpcd[3265]: eth0: adding route to 172.20.10.0/28 Sep 24 13:58:44 rivo dhcpcd[3265]: eth0: adding default route via 172.20.10.1 Sep 24 13:58:44 rivo ntpd[4024]: ntpd exiting on signal 15 (Terminated) Sep 24 13:58:44 rivo systemd[1]: Stopping Network Time Service... Sep 24 13:58:44 rivo volumio[3650]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 24 13:58:44 rivo systemd[1]: ntp.service: Succeeded. Sep 24 13:58:44 rivo systemd[1]: Stopped Network Time Service. Sep 24 13:58:44 rivo volumio[3650]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 24 13:58:44 rivo systemd[1]: Starting Network Time Service... Sep 24 13:58:44 rivo volumio[3650]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 24 13:58:44 rivo ntpd[4071]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 24 13:58:44 rivo ntpd[4071]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 24 13:58:44 rivo systemd[1]: Started Network Time Service. Sep 24 13:58:44 rivo dhcpcd[3265]: eth0: deleting route to 169.254.0.0/16 Sep 24 13:58:44 rivo ntpd[4079]: proto: precision = 1.250 usec (-20) Sep 24 13:58:44 rivo ntpd[4079]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 24 13:58:44 rivo ntpd[4079]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 24 13:58:44 rivo ntpd[4079]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 637 days ago Sep 24 13:58:44 rivo ntpd[4079]: Listen and drop on 0 v6wildcard [::]:123 Sep 24 13:58:44 rivo ntpd[4079]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 24 13:58:44 rivo ntpd[4079]: Listen normally on 2 lo 127.0.0.1:123 Sep 24 13:58:44 rivo ntpd[4079]: Listen normally on 3 eth0 172.20.10.9:123 Sep 24 13:58:44 rivo ntpd[4079]: Listening on routing socket on fd #20 for interface updates Sep 24 13:58:44 rivo ntpd[4079]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 24 13:58:44 rivo ntpd[4079]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 24 13:58:45 rivo ntpd[4079]: Soliciting pool server 185.35.202.223 Sep 24 13:58:46 rivo ntpd[4079]: Soliciting pool server 185.181.61.91 Sep 24 13:58:46 rivo ntpd[4079]: Soliciting pool server 162.159.200.1 Sep 24 13:58:46 rivo volumio[3650]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 13:58:46 rivo volumio[3650]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Sep 24 13:58:46 rivo volumio[3650]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Sep 24 13:58:46 rivo volumio[3650]: errno: -3007, Sep 24 13:58:46 rivo volumio[3650]: code: 'ENOTFOUND', Sep 24 13:58:46 rivo volumio[3650]: syscall: 'getaddrinfo', Sep 24 13:58:46 rivo volumio[3650]: hostname: 'ws.audioscrobbler.com' Sep 24 13:58:46 rivo volumio[3650]: } Sep 24 13:58:46 rivo volumio[3650]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 24 13:58:47 rivo sudo[4104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-24 13:57 Sep 24 13:58:47 rivo sudo[4104]: 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"