Closed slipx06 closed 4 years ago
What you think, why does the udev monitor not work on your installation?
That's what I'm trying to figure out. This is a stock standard installation. Will continue to troubleshoot
If the udev is not running correct, it will break other stuff later with add-ons
Same issue here. Ubuntu Server 18.04.4 LTS. I had the issue with https://github.com/home-assistant/supervisor/issues/1556 and performed the manual installation which resulted in the issue above.
I thought my attempts to fix 1556 created this problem so I decided to start from scratch with a brand new image and it's showing this error straight after first login.
What you think, why does the udev monitor not work on your installation?
udev monitor seems to be working fine
home@HA:~$ udevadm monitor
monitor will print the received events for:
UDEV - the event which udev sends out after rule processing
KERNEL - the kernel uevent
KERNEL[175607.328644] remove /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(13027:systemd-hostnamed.service) (cgroup)
UDEV [175607.329626] remove /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(13027:systemd-hostnamed.service) (cgroup)
UDEV [175607.331611] remove /kernel/slab/:A-0000072/cgroup/eventpoll_pwq(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.333007] remove /kernel/slab/:A-0000128/cgroup/eventpoll_epi(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.333163] remove /kernel/slab/shmem_inode_cache/cgroup/shmem_inode_cache(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.333187] remove /kernel/slab/proc_inode_cache/cgroup/proc_inode_cache(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.333391] remove /kernel/slab/:A-0000256/cgroup/filp(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.333416] remove /kernel/slab/inode_cache/cgroup/inode_cache(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.333506] remove /kernel/slab/:A-0000208/cgroup/vm_area_struct(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.333526] remove /kernel/slab/:A-0002112/cgroup/mm_struct(13027:systemd-hostnamed.service) (cgroup)
UDEV [175607.338023] remove /kernel/slab/:A-0000128/cgroup/eventpoll_epi(13027:systemd-hostnamed.service) (cgroup)
UDEV [175607.339876] remove /kernel/slab/shmem_inode_cache/cgroup/shmem_inode_cache(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.340285] remove /kernel/slab/:A-0000192/cgroup/cred_jar(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.340485] remove /kernel/slab/anon_vma/cgroup/anon_vma(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.340509] remove /kernel/slab/:A-0000064/cgroup/pid(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.340652] remove /kernel/slab/radix_tree_node/cgroup/radix_tree_node(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.340835] remove /kernel/slab/:0004096/cgroup/kmalloc-4096(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.340855] remove /kernel/slab/:0001024/cgroup/kmalloc-1024(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.340934] remove /kernel/slab/:0000512/cgroup/kmalloc-512(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.340957] remove /kernel/slab/:0000256/cgroup/kmalloc-256(13027:systemd-hostnamed.service) (cgroup)
KERNEL[175607.341026] remove /kernel/slab/:0000032/cgroup/kmalloc-32(13027:systemd-hostnamed.service) (cgroup)
Performed a dist-upgrade which upgraded the udev and docker packages. Looks like the error might be gone.
sudo apt update && sudo apt dist-upgrade
EDIT1 confirmed the error is gone
EDIT2 updated to 209. Error is back
EDIT3 reboot seems to fix
I just provisioned a brand new VM using a fully-updated Ubuntu Server 18.04.4 LTS and installed Hassio via the usual installations and it's still showing the error:
20-03-05 20:24:01 CRITICAL (MainThread) [supervisor.hwmon] Not privileged to run udev. Update your installation!
[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] udev.sh: executing...
[20:23:43] INFO: Update udev informations
[cont-init.d] udev.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
[services.d] done.
20-03-05 20:23:44 INFO (MainThread) [__main__] Initialize Supervisor setup
20-03-05 20:23:44 INFO (MainThread) [supervisor.docker.network] Can't find Supervisor network, create new network
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Home Assistant configuration folder /data/homeassistant
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor SSL/TLS folder /data/ssl
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor Add-on data folder /data/addons/data
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor Add-on local repository folder /data/addons/local
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor Add-on git repositories folder /data/addons/git
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor temp folder /data/tmp
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor backup folder /data/backup
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor share folder /data/share
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor DNS folder /data/dns
20-03-05 20:23:44 INFO (MainThread) [supervisor.bootstrap] Create Supervisor audio folder /data/audio
20-03-05 20:23:44 WARNING (MainThread) [supervisor.utils.dt] Can't fetch freegeoip data: Cannot connect to host ip-api.com:80 ssl:None [Address not available]
20-03-05 20:23:44 INFO (SyncWorker_0) [supervisor.docker.supervisor] Attach to Supervisor homeassistant/amd64-hassio-supervisor with version 208
20-03-05 20:23:44 INFO (SyncWorker_0) [supervisor.docker.supervisor] Connect Supervisor to hassio Network
20-03-05 20:23:45 INFO (MainThread) [__main__] Setup Supervisor
20-03-05 20:23:45 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.systemd1 - /org/freedesktop/systemd1
20-03-05 20:23:45 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.hostname1 - /org/freedesktop/hostname1
20-03-05 20:23:45 WARNING (MainThread) [supervisor.dbus.rauc] Host has no rauc support. OTA updates have been disabled.
20-03-05 20:23:45 WARNING (MainThread) [supervisor.dbus.nmi_dns] No DnsManager support on the host. Local DNS functions have been disabled.
20-03-05 20:23:45 INFO (MainThread) [supervisor.host.info] Update local host information
20-03-05 20:23:45 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-03-05 20:23:45 INFO (MainThread) [supervisor.host.services] Update service information
20-03-05 20:23:45 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-03-05 20:23:45 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
20-03-05 20:23:45 INFO (MainThread) [supervisor.host.apparmor] Load AppArmor Profiles: {'hassio-supervisor'}
20-03-05 20:23:45 INFO (MainThread) [supervisor.host.services] Reload local service hassio-apparmor.service
20-03-05 20:23:45 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ReloadOrRestartUnit on /org/freedesktop/systemd1
20-03-05 20:23:45 INFO (MainThread) [supervisor.dns] No CoreDNS plugin Docker image homeassistant/amd64-hassio-dns found.
20-03-05 20:23:45 INFO (MainThread) [supervisor.dns] Setup CoreDNS plugin
20-03-05 20:23:45 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-03-05 20:23:45 INFO (MainThread) [supervisor.audio] No Audio plugin Docker image homeassistant/amd64-hassio-audio found.
20-03-05 20:23:45 INFO (MainThread) [supervisor.audio] Setup Audio plugin
20-03-05 20:23:45 INFO (SyncWorker_0) [supervisor.docker.interface] Pull image homeassistant/amd64-hassio-dns tag 1.
20-03-05 20:23:45 INFO (SyncWorker_1) [supervisor.docker.interface] Pull image homeassistant/amd64-hassio-audio tag 8.
20-03-05 20:23:48 INFO (MainThread) [supervisor.dns] CoreDNS plugin now installed
20-03-05 20:23:48 INFO (MainThread) [supervisor.misc.forwarder] Start DNS port forwarding to 172.30.32.3
20-03-05 20:23:48 INFO (MainThread) [supervisor.dns] Start CoreDNS plugin
20-03-05 20:23:48 INFO (MainThread) [supervisor.audio] Audio plugin now installed
20-03-05 20:23:48 INFO (MainThread) [supervisor.audio] Start Audio plugin
20-03-05 20:23:50 INFO (SyncWorker_8) [supervisor.docker.dns] Start DNS homeassistant/amd64-hassio-dns with version 1 - 172.30.32.3
20-03-05 20:23:50 INFO (SyncWorker_6) [supervisor.docker.audio] Start Audio homeassistant/amd64-hassio-audio with version 8 - 172.30.32.4
20-03-05 20:23:50 INFO (MainThread) [supervisor.homeassistant] No Home Assistant Docker image homeassistant/qemux86-64-homeassistant found.
20-03-05 20:23:50 INFO (MainThread) [supervisor.homeassistant] Setup HomeAssistant landingpage
20-03-05 20:23:50 INFO (SyncWorker_7) [supervisor.docker.interface] Pull image homeassistant/qemux86-64-homeassistant tag landingpage.
20-03-05 20:23:54 INFO (MainThread) [supervisor.homeassistant] Start HomeAssistant landingpage
20-03-05 20:23:54 INFO (SyncWorker_13) [supervisor.docker.homeassistant] Start homeassistant homeassistant/qemux86-64-homeassistant with version landingpage
20-03-05 20:23:59 INFO (MainThread) [supervisor.homeassistant] Detect a running Home Assistant instance
20-03-05 20:23:59 INFO (MainThread) [supervisor.store.git] Clone add-on https://github.com/hassio-addons/repository repository
20-03-05 20:23:59 INFO (MainThread) [supervisor.store.git] Clone add-on https://github.com/home-assistant/hassio-addons repository
20-03-05 20:24:01 INFO (MainThread) [supervisor.store] Load add-ons from store: 64 all - 64 new - 0 remove
20-03-05 20:24:01 INFO (MainThread) [supervisor.addons] Found 0 installed add-ons
20-03-05 20:24:01 INFO (MainThread) [supervisor.snapshots] Found 0 snapshot files
20-03-05 20:24:01 INFO (MainThread) [supervisor.discovery] Load 0 messages
20-03-05 20:24:01 INFO (MainThread) [supervisor.ingress] Load 0 ingress session
20-03-05 20:24:01 INFO (MainThread) [supervisor.secrets] Load Home Assistant secrets: 0
20-03-05 20:24:01 INFO (MainThread) [__main__] Run Supervisor
20-03-05 20:24:01 INFO (MainThread) [supervisor.api] Start API on 172.30.32.2
20-03-05 20:24:01 INFO (MainThread) [supervisor.addons] Phase 'initialize' start 0 add-ons
20-03-05 20:24:01 INFO (MainThread) [supervisor.addons] Phase 'system' start 0 add-ons
20-03-05 20:24:01 INFO (MainThread) [supervisor.addons] Phase 'services' start 0 add-ons
20-03-05 20:24:01 INFO (MainThread) [supervisor.core] Skip start of Home Assistant
20-03-05 20:24:01 INFO (MainThread) [supervisor.addons] Phase 'application' start 0 add-ons
20-03-05 20:24:01 INFO (MainThread) [supervisor.tasks] All core tasks are scheduled
20-03-05 20:24:01 CRITICAL (MainThread) [supervisor.hwmon] Not privileged to run udev. Update your installation!
20-03-05 20:24:01 INFO (MainThread) [supervisor.core] Supervisor is up and running
20-03-05 20:24:01 INFO (MainThread) [supervisor.homeassistant] Setup Home Assistant
20-03-05 20:24:01 INFO (SyncWorker_11) [supervisor.docker.interface] Update image homeassistant/qemux86-64-homeassistant:landingpage to homeassistant/qemux86-64-homeassistant:0.106.5
20-03-05 20:24:01 INFO (SyncWorker_11) [supervisor.docker.interface] Pull image homeassistant/qemux86-64-homeassistant tag 0.106.5.
20-03-05 20:24:01 INFO (MainThread) [supervisor.host.info] Update local host information
20-03-05 20:24:01 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-03-05 20:24:01 INFO (MainThread) [supervisor.host.services] Update service information
20-03-05 20:24:01 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-03-05 20:24:01 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
Failed to load cookie file from cookie: No such file or directory
20-03-05 20:24:25 INFO (SyncWorker_11) [supervisor.docker.interface] Stop homeassistant application
20-03-05 20:24:25 INFO (SyncWorker_11) [supervisor.docker.interface] Clean homeassistant application
20-03-05 20:24:25 INFO (MainThread) [supervisor.homeassistant] Home Assistant docker now installed
20-03-05 20:24:25 INFO (MainThread) [supervisor.homeassistant] Start Home Assistant
20-03-05 20:24:26 INFO (SyncWorker_0) [supervisor.docker.homeassistant] Start homeassistant homeassistant/qemux86-64-homeassistant with version 0.106.5
20-03-05 20:24:31 INFO (MainThread) [supervisor.homeassistant] Detect a running Home Assistant instance
20-03-05 20:24:31 INFO (SyncWorker_10) [supervisor.docker.interface] Cleanup images: ['homeassistant/qemux86-64-homeassistant:landingpage']
I just rebooted the VM and the error is now missing from the logs:
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
s6-svscanctl: fatal: unable to control /var/run/s6/services: supervisor not listening
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.
[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] udev.sh: executing...
[21:43:12] INFO: Update udev informations
[cont-init.d] udev.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
[services.d] done.
20-03-05 21:43:14 INFO (MainThread) [__main__] Initialize Supervisor setup
20-03-05 21:43:14 WARNING (MainThread) [supervisor.utils.dt] Can't fetch freegeoip data: Cannot connect to host ip-api.com:80 ssl:None [Address not available]
20-03-05 21:43:14 INFO (SyncWorker_0) [supervisor.docker.supervisor] Attach to Supervisor homeassistant/amd64-hassio-supervisor with version 208
20-03-05 21:43:14 INFO (MainThread) [__main__] Setup Supervisor
20-03-05 21:43:14 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.systemd1 - /org/freedesktop/systemd1
20-03-05 21:43:14 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.hostname1 - /org/freedesktop/hostname1
20-03-05 21:43:14 WARNING (MainThread) [supervisor.dbus.rauc] Host has no rauc support. OTA updates have been disabled.
20-03-05 21:43:14 INFO (MainThread) [supervisor.utils.gdbus] Connect to dbus: org.freedesktop.NetworkManager - /org/freedesktop/NetworkManager/DnsManager
20-03-05 21:43:14 INFO (MainThread) [supervisor.host.info] Update local host information
20-03-05 21:43:14 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/hostname1
20-03-05 21:43:14 INFO (MainThread) [supervisor.host.services] Update service information
20-03-05 21:43:14 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ListUnits on /org/freedesktop/systemd1
20-03-05 21:43:14 INFO (MainThread) [supervisor.host.network] Update local network DNS information
20-03-05 21:43:14 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.DBus.Properties.GetAll on /org/freedesktop/NetworkManager/DnsManager
20-03-05 21:43:14 INFO (MainThread) [supervisor.host.sound] Update PulseAudio information
20-03-05 21:43:14 INFO (MainThread) [supervisor.host.apparmor] Load AppArmor Profiles: {'hassio-supervisor'}
20-03-05 21:43:14 INFO (MainThread) [supervisor.host.services] Reload local service hassio-apparmor.service
20-03-05 21:43:14 INFO (MainThread) [supervisor.utils.gdbus] Call org.freedesktop.systemd1.Manager.ReloadOrRestartUnit on /org/freedesktop/systemd1
20-03-05 21:43:14 INFO (SyncWorker_3) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-audio with version 8
20-03-05 21:43:14 INFO (SyncWorker_0) [supervisor.docker.interface] Attach to homeassistant/amd64-hassio-dns with version 1
20-03-05 21:43:14 INFO (MainThread) [supervisor.audio] Start Audio plugin
20-03-05 21:43:14 INFO (MainThread) [supervisor.misc.forwarder] Start DNS port forwarding to 172.30.32.3
20-03-05 21:43:14 INFO (SyncWorker_7) [supervisor.docker.interface] Clean hassio_audio application
20-03-05 21:43:14 INFO (MainThread) [supervisor.dns] Start CoreDNS plugin
20-03-05 21:43:14 INFO (SyncWorker_2) [supervisor.docker.interface] Clean hassio_dns application
20-03-05 21:43:15 INFO (SyncWorker_2) [supervisor.docker.dns] Start DNS homeassistant/amd64-hassio-dns with version 1 - 172.30.32.3
20-03-05 21:43:16 INFO (SyncWorker_7) [supervisor.docker.audio] Start Audio homeassistant/amd64-hassio-audio with version 8 - 172.30.32.4
20-03-05 21:43:16 INFO (SyncWorker_4) [supervisor.docker.interface] Attach to homeassistant/qemux86-64-homeassistant with version 0.106.5
20-03-05 21:43:16 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/git/a0d7b954 repository
20-03-05 21:43:16 INFO (MainThread) [supervisor.store.git] Load add-on /data/addons/core repository
20-03-05 21:43:16 INFO (MainThread) [supervisor.store] Load add-ons from store: 64 all - 64 new - 0 remove
20-03-05 21:43:16 INFO (MainThread) [supervisor.addons] Found 0 installed add-ons
20-03-05 21:43:16 INFO (MainThread) [supervisor.updater] Fetch update data from https://version.home-assistant.io/stable.json
20-03-05 21:43:16 INFO (MainThread) [supervisor.snapshots] Found 0 snapshot files
20-03-05 21:43:16 INFO (MainThread) [supervisor.discovery] Load 0 messages
20-03-05 21:43:16 INFO (MainThread) [supervisor.ingress] Load 0 ingress session
20-03-05 21:43:16 INFO (MainThread) [supervisor.secrets] Load Home Assistant secrets: 1
20-03-05 21:43:16 INFO (MainThread) [__main__] Run Supervisor
20-03-05 21:43:16 INFO (MainThread) [supervisor.api] Start API on 172.30.32.2
20-03-05 21:43:16 INFO (MainThread) [supervisor.addons] Phase 'initialize' start 0 add-ons
20-03-05 21:43:16 INFO (MainThread) [supervisor.addons] Phase 'system' start 0 add-ons
20-03-05 21:43:16 INFO (MainThread) [supervisor.addons] Phase 'services' start 0 add-ons
20-03-05 21:43:16 INFO (SyncWorker_16) [supervisor.docker.interface] Start homeassistant/qemux86-64-homeassistant
@pvizeli does this mean that a reboot is all that's required to fix this?
Same issue with my Ubuntu 18.04, reboot does not solve it for me.
Difficult to say. Look like it's an issue with ubuntu and here udev on userland/kernel.
I'm experiencing this issue as well. I'm running Ubuntu LTS 16.04 still and reboots do not seem to fix.
Yes the error msg is back again for me as well. A reboot does not seem to fix this
Experiencing this on Ubuntu 18.04.03LTS under 209.
I just noticed this error after updating to Supervisor 218.
Just updated to 219 and got this error. HomeAssistant running with Ubuntu
experiencing it on Ubuntu 19.10 Home Assistant in Docker
Getting this on supervisor 219 Home Assistant (supervised) install on ubuntu 18.04
Experiencing this on Ubuntu 20.04 LTS and 209 supervisor.
I'm facing the same issue , Ubuntu 18.04, reboot didn't help .
SAME ISSUE,ubuntu 18.04
This repository gives just support for things on top of Home Assistant OS
Still having the same issue here. This feels like they're deliberately breaking this to get us moving from supervised to VM. I decided to ignore this error message for months, so far, no ill effect yet.
You can always add -v /dev:/dev
in /sbin/hassio-supervisor
to the docker run
command. This way udev symlinks will be exposed to the container and the error message disappears, updates will be possible. However, be aware that this way you expose real /dev
inside the container.
Updating to the latest hassio-supervisor located at https://raw.githubusercontent.com/home-assistant/supervised-installer/master/files/hassio-supervisor fixed this for me. You'll need to update the CONFIG_FILE variable and then restart the supervisor.
Locking down this issue, as it is off-topic for the project. Please use the community forums for custom solutions and discussions.
Home Assistant release with the issue: Supervisor 208
Operating environment (HassOS/Generic): Generic Ubuntu 18.04.4 LTS
When starting supervisor I get the following error
20-03-05 06:18:19 CRITICAL (MainThread) [supervisor.hwmon] Not privileged to run udev. Update your installation!
This is what I have tried so far to resolve it
Originally posted by @slipx06 in https://github.com/home-assistant/supervisor/issues/1568#issuecomment-595050593