-- Logs begin at Thu 2019-02-14 02:12:00 PST, end at Wed 2024-02-21 13:30:42 PST. -- Feb 21 13:29:02 volumio ntpd[824]: Soliciting pool server 65.100.46.166 Feb 21 13:29:02 volumio kernel: cp15barrier_handler: 605 callbacks suppressed Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97e10 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7eb1cc8 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7eb1d68 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7eb1cc8 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7eb1d68 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7eb1cc8 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7eb1d68 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b273c4 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b274f8 Feb 21 13:29:02 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b41ae0 Feb 21 13:29:08 volumio kernel: cp15barrier_handler: 891 callbacks suppressed Feb 21 13:29:08 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97e10 Feb 21 13:29:08 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97d70 Feb 21 13:29:08 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:29:08 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:29:08 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:29:08 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3d70 Feb 21 13:29:08 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:29:08 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:29:08 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:29:08 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:29:11 volumio dbus-daemon[646]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Feb 21 13:29:11 volumio sudo[3601]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:11 volumio volumio[3189]: Failed to set time zone: Connection timed out Feb 21 13:29:11 volumio volumio[3189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 13:29:11 volumio volumio[3189]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'America/Los_Angeles' Feb 21 13:29:11 volumio volumio[3189]: Failed to set time zone: Connection timed out Feb 21 13:29:11 volumio volumio[3189]: at checkExecSyncError (child_process.js:616:11) Feb 21 13:29:11 volumio volumio[3189]: at execSync (child_process.js:652:15) Feb 21 13:29:11 volumio volumio[3189]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1556:9) Feb 21 13:29:11 volumio volumio[3189]: at listOnTimeout (internal/timers.js:554:17) Feb 21 13:29:11 volumio volumio[3189]: at processTimers (internal/timers.js:497:7) { Feb 21 13:29:11 volumio volumio[3189]: status: 1, Feb 21 13:29:11 volumio volumio[3189]: signal: null, Feb 21 13:29:11 volumio volumio[3189]: output: [ null, '', 'Failed to set time zone: Connection timed out\n' ], Feb 21 13:29:11 volumio volumio[3189]: pid: 3600, Feb 21 13:29:11 volumio volumio[3189]: stdout: '', Feb 21 13:29:11 volumio volumio[3189]: stderr: 'Failed to set time zone: Connection timed out\n' Feb 21 13:29:11 volumio volumio[3189]: } Feb 21 13:29:11 volumio volumio[3189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 13:29:11 volumio sudo[3614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-02-21 13:28 Feb 21 13:29:12 volumio sudo[3614]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:12 volumio sudo[3614]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:14 volumio kernel: cp15barrier_handler: 369768 callbacks suppressed Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fe10 Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fd70 Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fe10 Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b00104 Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fd70 Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fe10 Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b001a0 Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b00104 Feb 21 13:29:14 volumio kernel: "node" (3606) uses deprecated CP15 Barrier instruction at 0xf7cafe10 Feb 21 13:29:14 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fd70 Feb 21 13:29:19 volumio kernel: cp15barrier_handler: 3672 callbacks suppressed Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fe10 Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fd70 Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fe10 Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b00104 Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fd70 Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fe10 Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b001a0 Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b00104 Feb 21 13:29:19 volumio kernel: "node" (3606) uses deprecated CP15 Barrier instruction at 0xf7cafe10 Feb 21 13:29:19 volumio kernel: "curl" (3619) uses deprecated CP15 Barrier instruction at 0xf7b7fd70 Feb 21 13:29:19 volumio volumio-remote-updater[636]: [2024-02-21 13:29:19] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Feb 21 13:29:19 volumio volumio-remote-updater[636]: [2024-02-21 13:29:19] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Feb 21 13:29:19 volumio volumio-remote-updater[636]: [2024-02-21 13:29:19] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Feb 21 13:29:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:29:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 21 13:29:19 volumio systemd[1]: Started dynamicswap service. Feb 21 13:29:19 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 21 13:29:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 21 13:29:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Feb 21 13:29:19 volumio systemd[1]: Stopped Volumio Backend Module. Feb 21 13:29:19 volumio systemd[1]: Started Volumio Backend Module. Feb 21 13:29:19 volumio systemd[1]: Started dynamicswap service. Feb 21 13:29:19 volumio systemd[1]: dynamicswap.service: Succeeded. Feb 21 13:29:22 volumio volumio[3627]: info: ------------------------------------------- Feb 21 13:29:22 volumio volumio[3627]: info: ----- Volumio3 ---- Feb 21 13:29:22 volumio volumio[3627]: info: ------------------------------------------- Feb 21 13:29:22 volumio volumio[3627]: info: ----- System startup ---- Feb 21 13:29:22 volumio volumio[3627]: info: ------------------------------------------- Feb 21 13:29:23 volumio volumio[3627]: info: MYVOLUMIO Environment detected Feb 21 13:29:23 volumio volumio[3627]: info: Plugin folders cleanup Feb 21 13:29:23 volumio volumio[3627]: info: Scanning into folder /volumio/app/plugins/ Feb 21 13:29:23 volumio volumio[3627]: info: Scanning category audio_interface Feb 21 13:29:23 volumio volumio[3627]: info: Scanning category miscellanea Feb 21 13:29:23 volumio volumio[3627]: info: Scanning category music_service Feb 21 13:29:23 volumio volumio[3627]: info: Scanning category plugins.json Feb 21 13:29:23 volumio volumio[3627]: info: Scanning category system_controller Feb 21 13:29:23 volumio volumio[3627]: info: Scanning category user_interface Feb 21 13:29:23 volumio volumio[3627]: info: Scanning into folder /data/plugins/ Feb 21 13:29:23 volumio volumio[3627]: info: Plugin folders cleanup completed Feb 21 13:29:23 volumio volumio[3627]: info: ------------------------------------------- Feb 21 13:29:23 volumio volumio[3627]: info: ----- Core plugins startup ---- Feb 21 13:29:23 volumio volumio[3627]: info: ------------------------------------------- Feb 21 13:29:23 volumio volumio[3627]: info: Loading plugins from folder /volumio/app/plugins/ Feb 21 13:29:23 volumio volumio[3627]: info: Adding plugin upnp to MyMusic Plugins Feb 21 13:29:23 volumio volumio[3627]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 21 13:29:23 volumio volumio[3627]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 21 13:29:23 volumio volumio[3627]: info: Loading plugins from folder /data/plugins/ Feb 21 13:29:23 volumio volumio[3627]: info: Loading plugin "system"... Feb 21 13:29:23 volumio volumio[3627]: info: Loading plugin "appearance"... Feb 21 13:29:24 volumio kernel: cp15barrier_handler: 3350771 callbacks suppressed Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf765d400 Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf765d420 Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf7659eb4 Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xa4dbb4 Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xa8fdd0 Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x8b1c40 Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x8b1c54 Feb 21 13:29:24 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xace794 Feb 21 13:29:24 volumio volumio-remote-updater[636]: [2024-02-21 13:29:24] [connect] Successful connection Feb 21 13:29:24 volumio volumio[3627]: info: Loading plugin "network"... Feb 21 13:29:24 volumio volumio[3627]: info: Refreshing Cached IP Addresses Feb 21 13:29:24 volumio sudo[3655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 13:29:24 volumio sudo[3655]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:24 volumio sudo[3655]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:24 volumio volumio[3627]: info: Loading plugin "services"... Feb 21 13:29:24 volumio sudo[3657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 13:29:24 volumio sudo[3657]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:24 volumio volumio[3627]: info: Loading plugin "alsa_controller"... Feb 21 13:29:24 volumio sudo[3657]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:24 volumio sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 21 13:29:24 volumio sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:24 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:24 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:24 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 13:29:24 volumio volumio[3627]: info: Loading plugin "wizard"... Feb 21 13:29:24 volumio volumio[3627]: info: Loading plugin "networkfs"... Feb 21 13:29:24 volumio volumio[3627]: info: Starting Udev Watcher for removable devices Feb 21 13:29:25 volumio volumio[3627]: info: Ignoring mount for partition: BOOT_EMMC Feb 21 13:29:25 volumio volumio[3627]: info: Ignoring mount for partition: ROOTFS_EMMC Feb 21 13:29:25 volumio volumio[3627]: info: Mounting Device BOOT Feb 21 13:29:25 volumio volumio[3627]: /bin/mkdir: cannot create directory ‘/mnt/USB/BOOT’: Permission denied Feb 21 13:29:25 volumio volumio[3627]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/BOOT" Feb 21 13:29:25 volumio volumio[3627]: /bin/mkdir: cannot create directory ‘/mnt/USB/BOOT’: Permission denied Feb 21 13:29:25 volumio sudo[3679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/BOOT -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 21 13:29:25 volumio sudo[3679]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:25 volumio sudo[3679]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:25 volumio volumio[3627]: mount: /mnt/USB/BOOT: mount point does not exist. Feb 21 13:29:25 volumio volumio[3627]: error: Failed to mount BOOT: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/BOOT" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 21 13:29:25 volumio volumio[3627]: mount: /mnt/USB/BOOT: mount point does not exist. Feb 21 13:29:25 volumio volumio[3627]: info: Mounting Device ROOTFS Feb 21 13:29:25 volumio volumio[3627]: /bin/mkdir: cannot create directory ‘/mnt/USB/ROOTFS’: Permission denied Feb 21 13:29:25 volumio volumio[3627]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/ROOTFS" Feb 21 13:29:25 volumio volumio[3627]: /bin/mkdir: cannot create directory ‘/mnt/USB/ROOTFS’: Permission denied Feb 21 13:29:25 volumio sudo[3687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/ROOTFS -o noatime Feb 21 13:29:25 volumio sudo[3687]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:25 volumio sudo[3687]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:25 volumio volumio[3627]: mount: /mnt/USB/ROOTFS: mount point does not exist. Feb 21 13:29:25 volumio volumio[3627]: error: Failed to mount ROOTFS: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/ROOTFS" -o noatime Feb 21 13:29:25 volumio volumio[3627]: mount: /mnt/USB/ROOTFS: mount point does not exist. Feb 21 13:29:25 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 13:29:25 volumio volumio[3627]: info: Loading plugin "volumio_command_line_client"... Feb 21 13:29:25 volumio volumio[3627]: info: Loading plugin "upnp"... Feb 21 13:29:25 volumio volumio[3627]: info: [1708550965283] Starting Upmpd Daemon Feb 21 13:29:25 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 13:29:25 volumio volumio[3627]: info: Loading plugin "my_music"... Feb 21 13:29:25 volumio volumio[3627]: info: Loading plugin "mpd"... Feb 21 13:29:25 volumio volumio[3627]: info: Loading plugin "upnp_browser"... Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "alarm-clock"... Feb 21 13:29:26 volumio sudo[3660]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "airplay_emulation"... Feb 21 13:29:26 volumio volumio[3627]: info: Starting Shairport Sync Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "last_100"... Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "webradio"... Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "i2s_dacs"... Feb 21 13:29:26 volumio volumio[3627]: info: I2S DAC not set, start Auto-detection Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "volumiodiscovery"... Feb 21 13:29:26 volumio volumio[3627]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 21 13:29:26 volumio volumio[3627]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 13:29:26 volumio node[3627]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 21 13:29:26 volumio volumio[3627]: *** WARNING *** For more information see Feb 21 13:29:26 volumio volumio[3627]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 21 13:29:26 volumio volumio[3627]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 13:29:26 volumio volumio[3627]: *** WARNING *** For more information see Feb 21 13:29:26 volumio node[3627]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 13:29:26 volumio node[3627]: *** WARNING *** For more information see Feb 21 13:29:26 volumio node[3627]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 21 13:29:26 volumio node[3627]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 21 13:29:26 volumio node[3627]: *** WARNING *** For more information see Feb 21 13:29:26 volumio volumio[3627]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 21 13:29:26 volumio volumio[3627]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/volumiodiscovery/config.json' Feb 21 13:29:26 volumio volumio[3627]: info: Discovery: Started advertising with name: Volumio Feb 21 13:29:26 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "outputs"... Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "albumart"... Feb 21 13:29:26 volumio volumio[3627]: info: Plugin example_plugin is not enabled Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "inputs"... Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "updater_comm"... Feb 21 13:29:26 volumio volumio[3627]: info: Plugin mpdemulation is not enabled Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "rest_api"... Feb 21 13:29:26 volumio volumio[3627]: info: Loading plugin "websocket"... Feb 21 13:29:26 volumio volumio[3627]: info: Loading i18n strings for locale ca Feb 21 13:29:26 volumio volumio[3627]: Updating browse sources language Feb 21 13:29:26 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:29:27 volumio volumio[3627]: Forking 3 albumart workers Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::initPlayerControls Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: Express server listening on port 3000 Feb 21 13:29:27 volumio volumio[3627]: [Metrics] WebUI: 6s 18.98ms Feb 21 13:29:27 volumio volumio[3627]: info: CoreStateMachine::resetVolumioState Feb 21 13:29:27 volumio volumio[3627]: info: CoreStateMachine::getcurrentVolume Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 13:29:27 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:27 volumio volumio[3627]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/audio_interface/alsa_controller/config.json' Feb 21 13:29:27 volumio volumio[3627]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 21 13:29:27 volumio volumio[3627]: info: Completed loading Core Plugins Feb 21 13:29:27 volumio volumio[3627]: info: Preparing to generate the ALSA configuration file Feb 21 13:29:27 volumio volumio[3627]: info: Cannot read play queue from file Feb 21 13:29:27 volumio volumio[3627]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 1 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 2 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 3 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 4 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 5 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 6 Feb 21 13:29:28 volumio volumio-remote-updater[636]: [2024-02-21 13:29:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1708550964 101 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7 Feb 21 13:29:28 volumio volumio[3627]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Home directory not accessible: Permission denied Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 8 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 9 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 10 Feb 21 13:29:28 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 11 Feb 21 13:29:28 volumio volumio[3627]: info: VolumeController:: Volume=undefined Mute =false Feb 21 13:29:28 volumio volumio[3627]: info: CoreStateMachine::pushState Feb 21 13:29:28 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::volumioPushState Feb 21 13:29:28 volumio volumio[3627]: info: CoreStateMachine::updateTrackBlock Feb 21 13:29:28 volumio volumio[3627]: info: CorePlayQueue::getTrackBlock Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 13:29:28 volumio volumio[3627]: info: CoreStateMachine::setRepeat null single undefined Feb 21 13:29:28 volumio volumio[3627]: info: CoreStateMachine::pushState Feb 21 13:29:28 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::volumioPushState Feb 21 13:29:28 volumio volumio[3627]: info: CoreStateMachine::setRandom null Feb 21 13:29:28 volumio volumio[3627]: info: CoreStateMachine::pushState Feb 21 13:29:28 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::volumioPushState Feb 21 13:29:28 volumio volumio[3627]: Starting albumart workers Feb 21 13:29:28 volumio volumio[3627]: Starting albumart workers Feb 21 13:29:28 volumio volumio[3627]: error: Cannot write /etc/asound.conf: Error: EACCES: permission denied, open '/home/volumio/.asoundrc' Feb 21 13:29:28 volumio volumio[3627]: info: Output device has changed, restarting MPD Feb 21 13:29:28 volumio volumio[3627]: info: Output device has changed, restarting Shairport Sync Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:28 volumio sudo[3738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 13:29:28 volumio sudo[3738]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:28 volumio sudo[3739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 13:29:28 volumio sudo[3739]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:28 volumio sudo[3738]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:28 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:28 volumio volumio[3627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:29:28 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:28 volumio volumio[3627]: info: ___________ START PLUGINS ___________ Feb 21 13:29:28 volumio volumio[3627]: info: ControllerMpd::onStart: Initializing MPD Feb 21 13:29:28 volumio volumio[3627]: info: Creating MPD Configuration file Feb 21 13:29:28 volumio volumio[3627]: Starting albumart workers Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 13:29:28 volumio volumio[3627]: info: [1708550968902] CoreMusicLibrary::Adding element Media Servers Feb 21 13:29:28 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:29:28 volumio systemd[1]: Listening on mpd.socket. Feb 21 13:29:28 volumio sudo[3747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 13:29:28 volumio sudo[3747]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:28 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:29:28 volumio sudo[3749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 13:29:28 volumio sudo[3749]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:28 volumio sudo[3747]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:28 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 21 13:29:28 volumio systemd[1]: mpd.service: Succeeded. Feb 21 13:29:28 volumio systemd[1]: Stopped Music Player Daemon. Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:29 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:29:29 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:29 volumio volumio[3627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:29:29 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:29 volumio volumio[3627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:29:29 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 13:29:29 volumio volumio[3627]: info: [1708550969156] CoreMusicLibrary::Adding element Last_100 Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 13:29:29 volumio volumio[3627]: info: [1708550969163] CoreMusicLibrary::Adding element Webradio Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 13:29:29 volumio volumio[3627]: info: Volumio Calling Home Feb 21 13:29:29 volumio kernel: cp15barrier_handler: 6775281 callbacks suppressed Feb 21 13:29:29 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x8aa898 Feb 21 13:29:29 volumio kernel: "node" (3705) uses deprecated CP15 Barrier instruction at 0xf77242c8 Feb 21 13:29:29 volumio kernel: "node" (3705) uses deprecated CP15 Barrier instruction at 0xf772409c Feb 21 13:29:29 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0xa83244 Feb 21 13:29:29 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x8aa898 Feb 21 13:29:29 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0xa4dbb4 Feb 21 13:29:29 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf765a2c8 Feb 21 13:29:29 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf765a09c Feb 21 13:29:29 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0xa83244 Feb 21 13:29:29 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xb03da8 Feb 21 13:29:29 volumio volumio[3627]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Home directory not accessible: Permission denied Feb 21 13:29:29 volumio volumio[3627]: info: MPD Permissions set Feb 21 13:29:29 volumio volumio[3627]: info: MPD Permissions set Feb 21 13:29:29 volumio volumio[3627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Feb 21 13:29:29 volumio volumio[3627]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:29 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:29:30 volumio volumio[3627]: info: VolumeController:: Volume=undefined Mute =false Feb 21 13:29:30 volumio volumio[3627]: info: CoreStateMachine::pushState Feb 21 13:29:30 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::volumioPushState Feb 21 13:29:30 volumio volumio[3627]: info: Discovery: adding ff119fd5-935a-4faf-a8f6-14279294ef83 Feb 21 13:29:30 volumio volumio[3627]: info: Discovery: Found device Volumio Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::volumioGetState Feb 21 13:29:30 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:29:30 volumio volumio[3627]: info: Discovery: this is already registered, ff119fd5-935a-4faf-a8f6-14279294ef83 Feb 21 13:29:30 volumio volumio[3627]: info: Discovery: Found device Volumio Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::volumioGetState Feb 21 13:29:30 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:29:30 volumio volumio[3627]: info: Starting Shairport Sync Feb 21 13:29:30 volumio volumio[3627]: info: Starting Shairport Sync Feb 21 13:29:30 volumio volumio[3627]: info: Starting Shairport Sync Feb 21 13:29:30 volumio sudo[3806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:29:30 volumio sudo[3806]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:30 volumio sudo[3808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:29:30 volumio sudo[3808]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 21 13:29:30 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 21 13:29:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 21 13:29:30 volumio sudo[3812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:29:30 volumio sudo[3812]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 21 13:29:30 volumio sudo[3808]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:30 volumio sudo[3806]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 21 13:29:30 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 21 13:29:30 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 21 13:29:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 21 13:29:30 volumio volumio[3627]: info: Shairport-Sync Started Feb 21 13:29:30 volumio volumio[3627]: Error adding Membership: Error: addMembership EINVAL Feb 21 13:29:30 volumio volumio[3627]: info: Shairport-Sync Started Feb 21 13:29:30 volumio volumio[3627]: info: CoreCommandRouter::volumioGetState Feb 21 13:29:30 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:29:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 21 13:29:30 volumio sudo[3812]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:30 volumio volumio[3627]: info: Shairport-Sync Started Feb 21 13:29:30 volumio volumio[3627]: info: Volumio called home Feb 21 13:29:31 volumio mpd[3753]: Feb 21 13:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:29:31 volumio mpd[3753]: Feb 21 13:29 : exception: Database corrupted Feb 21 13:29:31 volumio mpd[3753]: Feb 21 13:29 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:29:31 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:29:31 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:29:31 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:29:31 volumio sudo[3739]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:31 volumio sudo[3749]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:31 volumio volumio[3627]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 21 13:29:31 volumio volumio[3627]: Job for mpd.service failed because the control process exited with error code. Feb 21 13:29:31 volumio volumio[3627]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 21 13:29:31 volumio volumio[3627]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 21 13:29:31 volumio volumio[3627]: Job for mpd.service failed because the control process exited with error code. Feb 21 13:29:31 volumio volumio[3627]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 21 13:29:31 volumio volumio[3627]: info: Completed starting Core Plugins Feb 21 13:29:31 volumio volumio[3627]: info: ------------------------------------------- Feb 21 13:29:31 volumio volumio[3627]: info: ----- MyVolumio plugins startup ---- Feb 21 13:29:31 volumio volumio[3627]: info: ------------------------------------------- Feb 21 13:29:31 volumio volumio[3627]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 21 13:29:31 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:29:32 volumio volumio[3627]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Feb 21 13:29:32 volumio mpd[3819]: Feb 21 13:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:29:32 volumio mpd[3819]: Feb 21 13:29 : exception: Database corrupted Feb 21 13:29:32 volumio mpd[3819]: Feb 21 13:29 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:29:32 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:29:32 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:29:32 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:29:32 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:29:33 volumio mpd[3821]: Feb 21 13:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:29:33 volumio mpd[3821]: Feb 21 13:29 : exception: Database corrupted Feb 21 13:29:33 volumio mpd[3821]: Feb 21 13:29 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:29:33 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:29:33 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:29:33 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:29:33 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:29:34 volumio kernel: cp15barrier_handler: 1099008 callbacks suppressed Feb 21 13:29:34 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97e10 Feb 21 13:29:34 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97d70 Feb 21 13:29:34 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:29:34 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:29:34 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:29:34 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3d70 Feb 21 13:29:34 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:29:34 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:29:34 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:29:34 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:29:35 volumio sudo[3862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 13:29:35 volumio sudo[3862]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:35 volumio mpd[3822]: Feb 21 13:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:29:35 volumio mpd[3822]: Feb 21 13:29 : exception: Database corrupted Feb 21 13:29:35 volumio mpd[3822]: Feb 21 13:29 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:29:35 volumio sudo[3862]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:35 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:29:35 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:29:35 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:29:35 volumio systemd[1]: mpd.service: Start request repeated too quickly. Feb 21 13:29:35 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:29:35 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:29:35 volumio systemd[1]: mpd.socket: Failed with result 'service-start-limit-hit'. Feb 21 13:29:35 volumio volumio[3627]: error: MPD error: Error: read ECONNRESET Feb 21 13:29:35 volumio sudo[3867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 21 13:29:35 volumio sudo[3867]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:35 volumio volumio[3627]: error: MPD error: Error: read ECONNRESET Feb 21 13:29:35 volumio sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 13:29:35 volumio sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:35 volumio sudo[3865]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:35 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 21 13:29:35 volumio sudo[3867]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:35 volumio volumio[3627]: info: Upmpdcli Daemon Started Feb 21 13:29:35 volumio volumio[3627]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 21 13:29:38 volumio kernel: usb 1-1: USB disconnect, device number 2 Feb 21 13:29:38 volumio kernel: usb 1-1.1: USB disconnect, device number 4 Feb 21 13:29:38 volumio thd[645]: Error reading device '/dev/input/event2' Feb 21 13:29:39 volumio thd[645]: Error reading device '/dev/input/event3' Feb 21 13:29:39 volumio kernel: usb 1-1.2: USB disconnect, device number 5 Feb 21 13:29:39 volumio thd[645]: Error reading device '/dev/input/event4' Feb 21 13:29:39 volumio thd[645]: Error reading device '/dev/input/event5' Feb 21 13:29:39 volumio kernel: cp15barrier_handler: 390532 callbacks suppressed Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0x49f5f4 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0x49f614 Feb 21 13:29:39 volumio kernel: usb 1-1.6: USB disconnect, device number 6 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0xf7dc21a0 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0xf7dc21a0 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0xf7dc21a0 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0xf7dc21a0 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0xf7dc21a0 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0xf7dc21a0 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0xf7dc21a0 Feb 21 13:29:39 volumio kernel: "systemd-udevd" (389) uses deprecated CP15 Barrier instruction at 0xf7dc21a0 Feb 21 13:29:39 volumio thd[645]: Error reading device '/dev/input/event6' Feb 21 13:29:39 volumio thd[645]: Error reading device '/dev/input/event7' Feb 21 13:29:39 volumio volumio[3627]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 21 13:29:39 volumio thd[645]: Error reading device '/dev/input/event8' Feb 21 13:29:39 volumio thd[645]: Error reading device '/dev/input/event9' Feb 21 13:29:39 volumio thd[645]: Error reading device '/dev/input/event10' Feb 21 13:29:40 volumio volumio[3627]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket Feb 21 13:29:40 volumio volumio[3627]: error: connect ECONNREFUSED /run/mpd/socket {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"/run/mpd/socket"} Feb 21 13:29:40 volumio volumio[3627]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket Feb 21 13:29:40 volumio volumio[3627]: error: connect ECONNREFUSED /run/mpd/socket {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"/run/mpd/socket"} Feb 21 13:29:40 volumio kernel: usb 1-1: new full-speed USB device number 7 using xhci-hcd Feb 21 13:29:41 volumio kernel: usb 1-1: New USB device found, idVendor=0a12, idProduct=1004, bcdDevice=36.05 Feb 21 13:29:41 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 13:29:41 volumio kernel: usb 1-1: Product: FiiO BTA30 Feb 21 13:29:41 volumio kernel: usb 1-1: Manufacturer: FiiO Feb 21 13:29:41 volumio kernel: usb 1-1: SerialNumber: ABCDEF0123456789 Feb 21 13:29:41 volumio kernel: usbcore: registered new interface driver snd-usb-audio Feb 21 13:29:41 volumio systemd-udevd[3873]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Feb 21 13:29:41 volumio volumio[3627]: info: Feb 21 13:29:41 volumio volumio[3627]: ---------------------------- USB Audio Device Attached Feb 21 13:29:41 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 21 13:29:44 volumio kernel: cp15barrier_handler: 56112 callbacks suppressed Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf76cee10 Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf773fe7c Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf77416c8 Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf76ced70 Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf76cee10 Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:44 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x8477d4 Feb 21 13:29:44 volumio volumio[3627]: verbose: New Socket.io Connection to 192.168.60.190 from 192.168.60.3 UA: Mozilla/5.0 (Windows NT 10.0; WOW64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6332.209 Safari/537.36 Total Clients: 13 Feb 21 13:29:44 volumio volumio[3627]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 13:29:44 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:29:45 volumio volumio[3627]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 13:29:48 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 13:29:48 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: info: Setting mixer ACODEC for card P230-Q200 Feb 21 13:29:48 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 13:29:48 volumio volumio[3627]: info: Updating Volume Controller Parameters: Device: 0 Name: P230-Q200 Mixer: ACODEC Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 13:29:48 volumio volumio[3627]: info: Disabling external Volume Control Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 13:29:48 volumio volumio[3627]: info: Preparing to generate the ALSA configuration file Feb 21 13:29:48 volumio volumio[3627]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 13:29:48 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 13:29:48 volumio volumio[3627]: error: Cannot write /etc/asound.conf: Error: EACCES: permission denied, open '/home/volumio/.asoundrc' Feb 21 13:29:48 volumio volumio[3627]: info: Output device has changed, restarting MPD Feb 21 13:29:48 volumio volumio[3627]: info: Output device has changed, restarting Shairport Sync Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:48 volumio sudo[3895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 13:29:48 volumio sudo[3895]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:48 volumio sudo[3895]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:48 volumio sudo[3897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 13:29:48 volumio sudo[3897]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:48 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:29:48 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:29:48 volumio volumio[3627]: info: VolumeController:: Volume=undefined Mute =false Feb 21 13:29:48 volumio volumio[3627]: info: CoreStateMachine::pushState Feb 21 13:29:48 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:29:48 volumio volumio[3627]: info: CoreCommandRouter::volumioPushState Feb 21 13:29:48 volumio systemd[1]: Listening on mpd.socket. Feb 21 13:29:48 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:29:49 volumio volumio[3627]: info: MPD Permissions set Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 21 13:29:49 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:29:49 volumio volumio[3627]: info: Starting Shairport Sync Feb 21 13:29:49 volumio sudo[3912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:29:49 volumio sudo[3912]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 21 13:29:49 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 21 13:29:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 21 13:29:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 21 13:29:49 volumio sudo[3912]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:49 volumio volumio[3627]: info: Shairport-Sync Started Feb 21 13:29:49 volumio volumio[3627]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/audio_interface/alsa_controller/config.json' Feb 21 13:29:49 volumio kernel: cp15barrier_handler: 413775 callbacks suppressed Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf76cee10 Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf773fe7c Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0xf77416c8 Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x536584 Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x536590 Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x535e18 Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x535e30 Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:49 volumio kernel: "node" (3627) uses deprecated CP15 Barrier instruction at 0x50324c Feb 21 13:29:50 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 21 13:29:50 volumio volumio[3627]: info: Loading i18n strings for locale zh Feb 21 13:29:50 volumio volumio[3627]: Updating browse sources language Feb 21 13:29:50 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 13:29:50 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 21 13:29:50 volumio volumio[3627]: info: Setting timezone to America/Los_Angeles Feb 21 13:29:50 volumio sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 21 13:29:50 volumio sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:50 volumio sudo[3918]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:50 volumio sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Los_Angeles /etc/localtime Feb 21 13:29:50 volumio sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:50 volumio sudo[3922]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:50 volumio sudo[3925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 21 13:29:50 volumio sudo[3925]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:50 volumio sudo[3925]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:50 volumio sudo[3928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Los_Angeles Feb 21 13:29:50 volumio sudo[3928]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:29:50 volumio dbus-daemon[646]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.73' (uid=0 pid=3929 comm="/usr/bin/timedatectl set-timezone America/Los_Ange" label="kernel") Feb 21 13:29:50 volumio systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Feb 21 13:29:50 volumio systemd[1]: systemd-timedated.service: Failed with result 'resources'. Feb 21 13:29:50 volumio systemd[1]: Failed to start Time & Date Service. Feb 21 13:29:50 volumio mpd[3904]: Feb 21 13:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:29:50 volumio mpd[3904]: Feb 21 13:29 : exception: Database corrupted Feb 21 13:29:50 volumio mpd[3904]: Feb 21 13:29 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:29:50 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:29:50 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:29:50 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:29:50 volumio sudo[3897]: pam_unix(sudo:session): session closed for user root Feb 21 13:29:54 volumio kernel: cp15barrier_handler: 104761 callbacks suppressed Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0xf784be10 Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0xf78bce7c Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0xf78be6c8 Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0x536584 Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0x536590 Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0x535e18 Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0x535e30 Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:29:54 volumio kernel: "node" (3700) uses deprecated CP15 Barrier instruction at 0x50324c Feb 21 13:29:57 volumio ntpd[824]: Soliciting pool server 71.162.136.44 Feb 21 13:30:00 volumio kernel: cp15barrier_handler: 868 callbacks suppressed Feb 21 13:30:00 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97e10 Feb 21 13:30:00 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97d70 Feb 21 13:30:00 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:00 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:00 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:00 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3d70 Feb 21 13:30:00 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:00 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:00 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:00 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:04 volumio ntpd[824]: Soliciting pool server 5.161.184.148 Feb 21 13:30:05 volumio ntpd[824]: Soliciting pool server 2620:b0:2000:102::2:123 Feb 21 13:30:05 volumio kernel: cp15barrier_handler: 656493 callbacks suppressed Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3d70 Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78c7c68 Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:05 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:08 volumio ntpd[824]: Soliciting pool server 66.205.249.28 Feb 21 13:30:10 volumio kernel: cp15barrier_handler: 730 callbacks suppressed Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0xf77d2e10 Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0xf7843e7c Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0xf78456c8 Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x536584 Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x536590 Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x535e18 Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x535e30 Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x870f4c Feb 21 13:30:10 volumio kernel: "node" (3699) uses deprecated CP15 Barrier instruction at 0x50324c Feb 21 13:30:15 volumio dbus-daemon[646]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Feb 21 13:30:15 volumio sudo[3928]: pam_unix(sudo:session): session closed for user root Feb 21 13:30:15 volumio volumio[3627]: Failed to set time zone: Connection timed out Feb 21 13:30:15 volumio volumio[3627]: info: Could not set timezone, retrying Feb 21 13:30:15 volumio volumio[3627]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/miscellanea/appearance/config.json' Feb 21 13:30:15 volumio volumio[3627]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/system/config.json' Feb 21 13:30:15 volumio volumio[3627]: error: MyVolumio Plugin failed to start in a timely fashion Feb 21 13:30:15 volumio volumio[3627]: info: BOOT COMPLETED Feb 21 13:30:15 volumio volumio[3627]: [Metrics] CommandRouter: 53s 291.04ms Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::volumiosetStartupVolume Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::Close All Modals sent Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::Close All Modals sent Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 13:30:15 volumio volumio[3627]: info: CoreStateMachine::getcurrentVolume Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 13:30:15 volumio volumio[3627]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Home directory not accessible: Permission denied Feb 21 13:30:15 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:30:15 volumio volumio[3627]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 21 13:30:15 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:30:15 volumio volumio[3627]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 21 13:30:15 volumio volumio[3627]: aplay: main:828: audio open error: No such device Feb 21 13:30:15 volumio volumio[3627]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 21 13:30:15 volumio volumio[3627]: Job for mpd.service failed because the control process exited with error code. Feb 21 13:30:15 volumio volumio[3627]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 21 13:30:15 volumio sudo[3939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 21 13:30:15 volumio sudo[3939]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:30:15 volumio volumio[3627]: info: VolumeController:: Volume=undefined Mute =false Feb 21 13:30:15 volumio volumio[3627]: info: CoreStateMachine::pushState Feb 21 13:30:15 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::volumioPushState Feb 21 13:30:15 volumio volumio[3627]: info: CoreStateMachine::updateTrackBlock Feb 21 13:30:15 volumio volumio[3627]: info: CorePlayQueue::getTrackBlock Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 13:30:15 volumio kernel: cp15barrier_handler: 4170691 callbacks suppressed Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b58400 Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b58420 Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b58ec8 Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b58ee8 Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b33d80 Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b54eb4 Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b54eb4 Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b32a0c Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b58400 Feb 21 13:30:15 volumio kernel: "(mpd)" (3936) uses deprecated CP15 Barrier instruction at 0xf7b58420 Feb 21 13:30:15 volumio volumio[3627]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Home directory not accessible: Permission denied Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 13:30:15 volumio volumio[3627]: info: VolumeController:: Volume=undefined Mute =false Feb 21 13:30:15 volumio volumio[3627]: info: CoreStateMachine::pushState Feb 21 13:30:15 volumio volumio[3627]: info: CorePlayQueue::getTrack 0 Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::volumioPushState Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 21 13:30:15 volumio volumio[3627]: info: System name has changed, restarting Shairport Sync Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:30:15 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:30:15 volumio volumio[3627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 13:30:15 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:30:15 volumio volumio[3627]: info: Discovery: Restarting Advertising due to device name change Feb 21 13:30:15 volumio volumio[3627]: error: Failed to set hostname: Error: EACCES: permission denied, open '/etc/hostname' Feb 21 13:30:15 volumio sudo[3948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 21 13:30:15 volumio sudo[3948]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:30:15 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 21 13:30:15 volumio systemd[1]: upmpdcli.service: Main process exited, code=killed, status=15/TERM Feb 21 13:30:15 volumio systemd[1]: upmpdcli.service: Succeeded. Feb 21 13:30:15 volumio sudo[3948]: pam_unix(sudo:session): session closed for user root Feb 21 13:30:16 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 13:30:16 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:30:16 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 13:30:16 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:30:16 volumio volumio[3627]: info: Setting mixer ACODEC for card P230-Q200 Feb 21 13:30:16 volumio volumio[3627]: Home directory not accessible: Permission denied Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 13:30:16 volumio volumio[3627]: info: Updating Volume Controller Parameters: Device: 0 Name: P230-Q200 Mixer: ACODEC Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 13:30:16 volumio volumio[3627]: info: Disabling external Volume Control Feb 21 13:30:16 volumio volumio[3627]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 13:30:16 volumio volumio[3627]: info: Preparing to generate the ALSA configuration file Feb 21 13:30:16 volumio volumio[3627]: info: Starting Shairport Sync Feb 21 13:30:16 volumio sudo[3962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 13:30:16 volumio sudo[3962]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:30:16 volumio sudo[3964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Los_Angeles Feb 21 13:30:16 volumio sudo[3964]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 13:30:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 21 13:30:16 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 21 13:30:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 21 13:30:16 volumio dbus-daemon[646]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.74' (uid=0 pid=3966 comm="/usr/bin/timedatectl set-timezone America/Los_Ange" label="kernel") Feb 21 13:30:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 21 13:30:16 volumio sudo[3962]: pam_unix(sudo:session): session closed for user root Feb 21 13:30:16 volumio systemd[1]: systemd-timedated.service: Failed to run 'start' task: No space left on device Feb 21 13:30:16 volumio systemd[1]: systemd-timedated.service: Failed with result 'resources'. Feb 21 13:30:16 volumio systemd[1]: Failed to start Time & Date Service. Feb 21 13:30:17 volumio mpd[3936]: Feb 21 13:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:30:17 volumio mpd[3936]: Feb 21 13:30 : exception: Database corrupted Feb 21 13:30:17 volumio sudo[3939]: pam_unix(sudo:session): session closed for user root Feb 21 13:30:17 volumio mpd[3936]: Feb 21 13:30 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:30:17 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:30:17 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:30:17 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:30:17 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:30:18 volumio mpd[3972]: Feb 21 13:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:30:18 volumio mpd[3972]: Feb 21 13:30 : exception: Database corrupted Feb 21 13:30:18 volumio mpd[3972]: Feb 21 13:30 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:30:18 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:30:18 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:30:18 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:30:18 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:30:19 volumio mpd[3973]: Feb 21 13:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:30:19 volumio mpd[3973]: Feb 21 13:30 : exception: Database corrupted Feb 21 13:30:19 volumio mpd[3973]: Feb 21 13:30 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:30:19 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:30:19 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:30:19 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:30:19 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:30:21 volumio kernel: cp15barrier_handler: 519426 callbacks suppressed Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf40fac18 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf40fada8 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf40fadd0 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf408df64 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf408dfd8 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf3fd8654 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf3fd8678 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf4101a50 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf4101a98 Feb 21 13:30:21 volumio kernel: "mpd" (3974) uses deprecated CP15 Barrier instruction at 0xf40feef8 Feb 21 13:30:21 volumio mpd[3974]: Feb 21 13:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:30:21 volumio mpd[3974]: Feb 21 13:30 : exception: Database corrupted Feb 21 13:30:21 volumio mpd[3974]: Feb 21 13:30 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:30:21 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:30:21 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:30:21 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:30:21 volumio systemd[1]: Starting Music Player Daemon... Feb 21 13:30:22 volumio mpd[3975]: Feb 21 13:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 13:30:22 volumio mpd[3975]: Feb 21 13:30 : exception: Database corrupted Feb 21 13:30:22 volumio mpd[3975]: Feb 21 13:30 : exception: Failed to open database plugin; Can't open db file "/var/lib/mpd/tag_cache" for reading/writing: Permission denied Feb 21 13:30:22 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 21 13:30:22 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:30:22 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:30:22 volumio systemd[1]: mpd.service: Start request repeated too quickly. Feb 21 13:30:22 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 21 13:30:22 volumio systemd[1]: Failed to start Music Player Daemon. Feb 21 13:30:22 volumio systemd[1]: mpd.socket: Failed with result 'service-start-limit-hit'. Feb 21 13:30:26 volumio kernel: cp15barrier_handler: 463390 callbacks suppressed Feb 21 13:30:26 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97e10 Feb 21 13:30:26 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97d70 Feb 21 13:30:26 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:26 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:26 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:26 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3d70 Feb 21 13:30:26 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:26 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:26 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:26 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:32 volumio kernel: cp15barrier_handler: 313 callbacks suppressed Feb 21 13:30:32 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97e10 Feb 21 13:30:32 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97d70 Feb 21 13:30:32 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:32 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:32 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:32 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3d70 Feb 21 13:30:32 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:32 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:32 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:32 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:38 volumio kernel: cp15barrier_handler: 5253 callbacks suppressed Feb 21 13:30:38 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97e10 Feb 21 13:30:38 volumio kernel: "ntpd" (824) uses deprecated CP15 Barrier instruction at 0xf7b97d70 Feb 21 13:30:38 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:38 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:38 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:38 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3d70 Feb 21 13:30:38 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf7cf3e10 Feb 21 13:30:38 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:38 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561ec Feb 21 13:30:38 volumio kernel: "systemd-journal" (363) uses deprecated CP15 Barrier instruction at 0xf78561cc Feb 21 13:30:41 volumio dbus-daemon[646]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Feb 21 13:30:41 volumio sudo[3964]: pam_unix(sudo:session): session closed for user root Feb 21 13:30:41 volumio volumio[3627]: Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Feb 21 13:30:41 volumio volumio[3627]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 13:30:41 volumio volumio[3627]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'America/Los_Angeles' Feb 21 13:30:41 volumio volumio[3627]: Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Feb 21 13:30:41 volumio volumio[3627]: at checkExecSyncError (child_process.js:616:11) Feb 21 13:30:41 volumio volumio[3627]: at execSync (child_process.js:652:15) Feb 21 13:30:41 volumio volumio[3627]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1556:9) Feb 21 13:30:41 volumio volumio[3627]: at listOnTimeout (internal/timers.js:554:17) Feb 21 13:30:41 volumio volumio[3627]: at processTimers (internal/timers.js:497:7) { Feb 21 13:30:41 volumio volumio[3627]: status: 1, Feb 21 13:30:41 volumio volumio[3627]: signal: null, Feb 21 13:30:41 volumio volumio[3627]: output: [ Feb 21 13:30:41 volumio volumio[3627]: null, Feb 21 13:30:41 volumio volumio[3627]: '', Feb 21 13:30:41 volumio volumio[3627]: "Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms)\n" Feb 21 13:30:41 volumio volumio[3627]: ], Feb 21 13:30:41 volumio volumio[3627]: pid: 3963, Feb 21 13:30:41 volumio volumio[3627]: stdout: '', Feb 21 13:30:41 volumio volumio[3627]: stderr: "Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms)\n" Feb 21 13:30:41 volumio volumio[3627]: } Feb 21 13:30:41 volumio volumio[3627]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 13:30:42 volumio sudo[4026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-02-21 13:29 Feb 21 13:30:42 volumio sudo[4026]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"