supervisor icon indicating copy to clipboard operation
supervisor copied to clipboard
verified publisher icon home-assistant

RPi 3 Upgrade from 8.5 to 9.0 fails

Open Uthrom opened this issue 3 years ago • 7 comments

Describe the issue you are experiencing

Trying to upgrade HAOS from 8.5 to 9.0 from GUI seems to time out.

When running it from command line, I get the following:

➜ backup ha os update --no-progress --log-level debug DEBU[0000] Adding homedir to searchpath homedir=/root INFO[0000] No configfile found
DEBU[0000] Debug flags apiToken=f8daa3debc3beebcd62b3ef5b3ee751064150d456e345144f4364dd5d6f781ade7352c242e00345f84bee288ee274f884a1359ffbfd7af44 cfgFile= endpoint=supervisor logLevel=debug noProgress=true rawJSON=false DEBU[0000] os update args="[]" DEBU[0000] [GenerateURI] base=supervisor command=update section=os DEBU[0000] [GenerateURI] Result uri="http://supervisor/os/update" url="http://supervisor/os/update" url(string)="http://supervisor/os/update" DEBU[2305] Response body="{"result": "error", "message": "Can't fetch OTA update from https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb: Response payload is not completed"}" fields.time=38m25.219007174s headers="map[Content-Length:[192] Content-Type:[application/json; charset=utf-8] Date:[Sun, 18 Sep 2022 23:38:06 GMT] Server:[Python/3.10 aiohttp/3.8.1]]" received-at="2022-09-18 18:38:06.549179766 -0500 CDT m=+2305.234044017" request="&{POST http://supervisor/os/update HTTP/1.1 1 1 map[Accept:[application/json] Authorization:[Bearer f8daa3debc3beebcd62b3ef5b3ee751064150d456e345144f4364dd5d6f781ade7352c242e00345f84bee288ee274f884a1359ffbfd7af44] User-Agent:[go-resty/2.7.0 (https://github.com/go-resty/resty)]] 0x2a4a30 0 [] false supervisor map[] map[] map[] 0x4000144040}" status="400 Bad Request" statuscode=400 Error: Can't fetch OTA update from https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb: Response payload is not completed ➜ backup

What operating system image do you use?

rpi3-64 (Raspberry Pi 3 64-bit OS)

What version of Home Assistant Operating System is installed?

8.5

Did you upgrade the Operating System.

No

Steps to reproduce the issue

  1. run the command: ha os update --no-progress --log-level debug
  2. Wait
  3. Observe above error message ...

Anything in the Supervisor logs that might be useful for us?

22-09-18 17:24:05 INFO (MainThread) [supervisor.api.middleware.security] /core/restart access from a0d7b954_ssh
22-09-18 17:24:05 INFO (SyncWorker_2) [supervisor.docker.interface] Restarting ghcr.io/home-assistant/raspberrypi3-64-homeassistant
22-09-18 17:24:59 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
22-09-18 17:25:02 INFO (MainThread) [supervisor.host.info] Updating local host information
22-09-18 17:25:05 INFO (MainThread) [supervisor.host.services] Updating service information
22-09-18 17:25:06 INFO (MainThread) [supervisor.host.network] Updating local network information
22-09-18 17:25:08 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
22-09-18 17:25:09 INFO (MainThread) [supervisor.host.manager] Host information reload completed
22-09-18 17:25:43 INFO (MainThread) [supervisor.addons] Add-on 'a0d7b954_nodered' successfully updated
22-09-18 17:25:43 INFO (SyncWorker_6) [supervisor.docker.interface] Cleanup images: ['ghcr.io/hassio-addons/node-red/aarch64:13.3.2']
22-09-18 17:25:43 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING
22-09-18 17:25:45 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
22-09-18 17:25:51 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
22-09-18 17:25:51 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/fredrikbaberg/hassio-addons repository
22-09-18 17:25:51 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
22-09-18 17:25:51 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
22-09-18 17:25:52 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
22-09-18 17:26:02 INFO (MainThread) [supervisor.store] Loading add-ons from store: 75 all - 0 new - 0 remove
22-09-18 17:26:02 INFO (MainThread) [supervisor.store] Loading add-ons from store: 75 all - 0 new - 0 remove
22-09-18 17:28:40 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance
22-09-18 17:32:48 INFO (MainThread) [supervisor.backups.manager] Found 7 backup files
22-09-18 17:32:48 INFO (MainThread) [supervisor.backups.manager] Found 7 backup files
22-09-18 17:33:02 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
22-09-18 17:33:10 INFO (MainThread) [supervisor.backups.manager] Removed backup file 5bb36f2e
22-09-18 17:33:10 INFO (MainThread) [supervisor.backups.manager] Removed backup file ca8d9472
22-09-18 17:33:10 INFO (MainThread) [supervisor.backups.manager] Removed backup file 5472a50b
22-09-18 17:33:10 INFO (MainThread) [supervisor.backups.manager] Removed backup file 6cb06c96
22-09-18 17:33:10 INFO (MainThread) [supervisor.backups.manager] Removed backup file 0a8ea333
22-09-18 17:33:11 INFO (MainThread) [supervisor.backups.manager] Found 2 backup files
22-09-18 17:33:16 INFO (MainThread) [supervisor.backups.manager] Found 2 backup files
22-09-18 17:33:17 INFO (MainThread) [supervisor.backups.manager] Found 2 backup files
22-09-18 17:33:33 INFO (MainThread) [supervisor.backups.manager] Creating new full backup with slug b97e93dc
22-09-18 17:33:33 INFO (MainThread) [supervisor.backups.manager] Backing up b97e93dc store Add-ons
22-09-18 17:33:33 INFO (MainThread) [supervisor.addons.addon] Building backup for add-on 774437fd_3dprinter
22-09-18 17:35:22 INFO (MainThread) [supervisor.addons.addon] Finish backup for addon 774437fd_3dprinter
22-09-18 17:35:22 INFO (MainThread) [supervisor.addons.addon] Building backup for add-on a0d7b954_ssh
22-09-18 17:35:23 INFO (MainThread) [supervisor.addons.addon] Finish backup for addon a0d7b954_ssh
22-09-18 17:35:23 INFO (MainThread) [supervisor.addons.addon] Building backup for add-on a0d7b954_vscode
22-09-18 17:35:28 INFO (MainThread) [supervisor.addons.addon] Finish backup for addon a0d7b954_vscode
22-09-18 17:35:28 INFO (MainThread) [supervisor.addons.addon] Building backup for add-on a0d7b954_nodered
22-09-18 17:35:28 INFO (MainThread) [supervisor.addons.addon] Finish backup for addon a0d7b954_nodered
22-09-18 17:35:28 INFO (MainThread) [supervisor.addons.addon] Building backup for add-on a0d7b954_adguard
22-09-18 17:35:29 INFO (MainThread) [supervisor.addons.addon] Finish backup for addon a0d7b954_adguard
22-09-18 17:35:30 INFO (MainThread) [supervisor.homeassistant.module] Backing up Home Assistant Core config folder
22-09-18 17:45:32 INFO (MainThread) [supervisor.homeassistant.module] Backup Home Assistant Core config folder done
22-09-18 17:45:32 INFO (MainThread) [supervisor.backups.manager] Backing up b97e93dc store folders
22-09-18 17:45:32 INFO (SyncWorker_4) [supervisor.backups.backup] Backing up folder share
22-09-18 17:45:32 INFO (SyncWorker_4) [supervisor.backups.backup] Backup folder share done
22-09-18 17:45:32 INFO (SyncWorker_6) [supervisor.backups.backup] Backing up folder addons/local
22-09-18 17:45:32 INFO (SyncWorker_6) [supervisor.backups.backup] Backup folder addons/local done
22-09-18 17:45:32 INFO (SyncWorker_0) [supervisor.backups.backup] Backing up folder ssl
22-09-18 17:45:32 INFO (SyncWorker_0) [supervisor.backups.backup] Backup folder ssl done
22-09-18 17:45:32 INFO (SyncWorker_2) [supervisor.backups.backup] Backing up folder media
22-09-18 17:45:32 INFO (SyncWorker_2) [supervisor.backups.backup] Backup folder media done
22-09-18 17:45:58 INFO (MainThread) [supervisor.backups.manager] Creating full backup with slug b97e93dc completed
22-09-18 17:45:58 INFO (MainThread) [supervisor.backups.manager] Found 3 backup files
22-09-18 17:57:24 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
22-09-18 17:59:01 INFO (MainThread) [supervisor.backups.manager] Found 3 backup files
22-09-18 17:59:01 INFO (MainThread) [supervisor.backups.manager] Found 3 backup files
22-09-18 17:59:10 INFO (MainThread) [supervisor.api.middleware.security] /os/info access from a0d7b954_ssh
22-09-18 17:59:41 INFO (MainThread) [supervisor.api.middleware.security] /os/update access from a0d7b954_ssh
22-09-18 17:59:41 INFO (MainThread) [supervisor.os.manager] Fetch OTA update from https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.RUNNING
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.FREE_SPACE/ContextType.SYSTEM
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.PWNED/ContextType.ADDON
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.SECURITY/ContextType.CORE
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.IPV4_CONNECTION_PROBLEM/ContextType.SYSTEM
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.DNS_SERVER_IPV6_ERROR/ContextType.DNS_SERVER
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.DNS_SERVER_FAILED/ContextType.DNS_SERVER
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.SUPERVISOR
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.check] System checks complete
22-09-18 18:02:08 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING
22-09-18 18:02:09 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
22-09-18 18:02:09 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING
22-09-18 18:02:09 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
22-09-18 18:03:02 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
22-09-18 18:33:03 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
22-09-18 18:38:06 ERROR (MainThread) [supervisor.os.manager] Can't fetch OTA update from https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb: Response payload is not completed
22-09-18 18:51:42 INFO (MainThread) [supervisor.api.middleware.security] /os/update access from a0d7b954_ssh

Anything in the Host logs that might be useful for us?

[1761969.429217] audit: type=1334 audit(1663486673.110:2126): prog-id=951 op=LOAD
[1761969.429393] audit: type=1334 audit(1663486673.110:2127): prog-id=952 op=LOAD
[1761970.557640] audit: type=1334 audit(1663486674.238:2128): prog-id=953 op=LOAD
[1761970.557962] audit: type=1334 audit(1663486674.238:2129): prog-id=954 op=LOAD
[1762000.671276] audit: type=1334 audit(1663486704.350:2130): prog-id=0 op=UNLOAD
[1762000.671330] audit: type=1334 audit(1663486704.350:2131): prog-id=0 op=UNLOAD
[1762001.450316] audit: type=1334 audit(1663486705.129:2132): prog-id=0 op=UNLOAD
[1762001.450374] audit: type=1334 audit(1663486705.129:2133): prog-id=0 op=UNLOAD
[1769573.287962] audit: type=1334 audit(1663494276.972:2134): prog-id=955 op=LOAD
[1769573.288987] audit: type=1334 audit(1663494276.973:2135): prog-id=956 op=LOAD
[1769574.421275] audit: type=1334 audit(1663494278.105:2136): prog-id=957 op=LOAD
[1769574.421564] audit: type=1334 audit(1663494278.105:2137): prog-id=958 op=LOAD
[1769604.551413] audit: type=1334 audit(1663494308.237:2138): prog-id=0 op=UNLOAD
[1769604.551483] audit: type=1334 audit(1663494308.237:2139): prog-id=0 op=UNLOAD
[1769605.333748] audit: type=1334 audit(1663494309.019:2140): prog-id=0 op=UNLOAD
[1769605.333819] audit: type=1334 audit(1663494309.019:2141): prog-id=0 op=UNLOAD
[1774458.227951] Bluetooth: hci0: failed to disable LE scan: status 0x1f
[1774458.227980] Bluetooth: hci0: command 0x200c tx timeout
[1774587.956430] Bluetooth: hci0: command 0x200c tx timeout
[1774705.985811] Bluetooth: hci0: Frame reassembly failed (-84)
[1774708.020736] Bluetooth: hci0: command 0x200c tx timeout
[1777178.661077] audit: type=1334 audit(1663501882.336:2142): prog-id=959 op=LOAD
[1777178.661292] audit: type=1334 audit(1663501882.336:2143): prog-id=960 op=LOAD
[1777179.809786] audit: type=1334 audit(1663501883.484:2144): prog-id=961 op=LOAD
[1777179.810028] audit: type=1334 audit(1663501883.485:2145): prog-id=962 op=LOAD
[1777209.942315] audit: type=1334 audit(1663501913.617:2146): prog-id=0 op=UNLOAD
[1777209.942378] audit: type=1334 audit(1663501913.617:2147): prog-id=0 op=UNLOAD
[1777210.727303] audit: type=1334 audit(1663501914.402:2148): prog-id=0 op=UNLOAD
[1777210.727350] audit: type=1334 audit(1663501914.402:2149): prog-id=0 op=UNLOAD
[1783396.500413] Bluetooth: hci0: Frame reassembly failed (-84)
[1783396.500450] Bluetooth: hci0: unknown advertising packet type: 0xe5
[1783396.501923] Bluetooth: hci0: Frame reassembly failed (-84)
[1783396.894656] Bluetooth: hci0: Frame reassembly failed (-84)
[1783396.894690] Bluetooth: hci0: Received unexpected HCI Event 00000000
[1783396.895817] Bluetooth: hci0: Frame reassembly failed (-84)
[1784783.895059] audit: type=1334 audit(1663509487.540:2150): prog-id=963 op=LOAD
[1784783.895345] audit: type=1334 audit(1663509487.540:2151): prog-id=964 op=LOAD
[1784785.056461] audit: type=1334 audit(1663509488.701:2152): prog-id=965 op=LOAD
[1784785.056645] audit: type=1334 audit(1663509488.701:2153): prog-id=966 op=LOAD
[1784815.167575] audit: type=1334 audit(1663509518.812:2154): prog-id=0 op=UNLOAD
[1784815.167633] audit: type=1334 audit(1663509518.812:2155): prog-id=0 op=UNLOAD
[1784815.983598] audit: type=1334 audit(1663509519.628:2156): prog-id=0 op=UNLOAD
[1784815.983651] audit: type=1334 audit(1663509519.628:2157): prog-id=0 op=UNLOAD
[1785707.951704] Bluetooth: hci0: Frame reassembly failed (-84)
[1785709.998023] Bluetooth: hci0: command 0x200c tx timeout
[1785709.998040] Bluetooth: hci0: failed to disable LE scan: status 0x1f
[1787089.495581] Bluetooth: hci0: Frame reassembly failed (-84)
[1787089.497448] Bluetooth: hci0: Frame reassembly failed (-84)
[1789757.018954] Bluetooth: hci0: Frame reassembly failed (-84)
[1789757.020677] Bluetooth: hci0: Frame reassembly failed (-84)
[1789758.351711] Bluetooth: hci0: Frame reassembly failed (-84)
[1789758.351750] Bluetooth: hci0: Received unexpected HCI Event 00000000
[1789758.353359] Bluetooth: hci0: Frame reassembly failed (-84)
[1791228.318937] Bluetooth: hci0: Frame reassembly failed (-90)
[1791228.320708] Bluetooth: hci0: Frame reassembly failed (-84)
[1791228.751444] Bluetooth: hci0: Frame reassembly failed (-84)
[1791228.751477] Bluetooth: hci0: Received unexpected HCI Event 00000000
[1791228.752943] Bluetooth: hci0: Frame reassembly failed (-84)
[1791229.177074] Bluetooth: hci0: Frame reassembly failed (-90)
[1791229.177096] Bluetooth: hci0: Received unexpected HCI Event 00000000
[1791229.178581] Bluetooth: hci0: Frame reassembly failed (-84)
[1791230.707908] Bluetooth: hci0: Frame reassembly failed (-84)
[1791230.707940] Bluetooth: hci0: Received unexpected HCI Event 00000000
[1791230.709394] Bluetooth: hci0: Frame reassembly failed (-84)
[1791233.782995] Bluetooth: hci0: Frame reassembly failed (-84)
[1791233.783022] Bluetooth: hci0: Received unexpected HCI Event 00000000
[1791233.784323] Bluetooth: hci0: Frame reassembly failed (-84)
[1792387.677894] audit: type=1334 audit(1663517091.338:2158): prog-id=967 op=LOAD
[1792387.678102] audit: type=1334 audit(1663517091.338:2159): prog-id=968 op=LOAD
[1792388.831619] audit: type=1334 audit(1663517092.492:2160): prog-id=969 op=LOAD
[1792388.831879] audit: type=1334 audit(1663517092.492:2161): prog-id=970 op=LOAD
[1792418.975604] audit: type=1334 audit(1663517122.636:2162): prog-id=0 op=UNLOAD
[1792418.975647] audit: type=1334 audit(1663517122.636:2163): prog-id=0 op=UNLOAD
[1792419.788524] audit: type=1334 audit(1663517123.449:2164): prog-id=0 op=UNLOAD
[1792419.788575] audit: type=1334 audit(1663517123.449:2165): prog-id=0 op=UNLOAD
[1799167.531427] Bluetooth: hci0: Frame reassembly failed (-84)
[1799167.532657] Bluetooth: hci0: Frame reassembly failed (-84)
[1799991.492932] audit: type=1334 audit(1663524695.149:2166): prog-id=971 op=LOAD
[1799991.493351] audit: type=1334 audit(1663524695.150:2167): prog-id=972 op=LOAD
[1799992.618235] audit: type=1334 audit(1663524696.275:2168): prog-id=973 op=LOAD
[1799992.618448] audit: type=1334 audit(1663524696.275:2169): prog-id=974 op=LOAD
[1800022.736238] audit: type=1334 audit(1663524726.393:2170): prog-id=0 op=UNLOAD
[1800022.736292] audit: type=1334 audit(1663524726.393:2171): prog-id=0 op=UNLOAD
[1800023.539264] audit: type=1334 audit(1663524727.196:2172): prog-id=0 op=UNLOAD
[1800023.539319] audit: type=1334 audit(1663524727.196:2173): prog-id=0 op=UNLOAD
[1807595.267712] audit: type=1334 audit(1663532298.886:2174): prog-id=975 op=LOAD
[1807595.267935] audit: type=1334 audit(1663532298.886:2175): prog-id=976 op=LOAD
[1807596.364456] audit: type=1334 audit(1663532299.983:2176): prog-id=977 op=LOAD
[1807596.364632] audit: type=1334 audit(1663532299.983:2177): prog-id=978 op=LOAD
[1807626.447486] audit: type=1334 audit(1663532330.066:2178): prog-id=0 op=UNLOAD
[1807626.447566] audit: type=1334 audit(1663532330.066:2179): prog-id=0 op=UNLOAD
[1807627.274319] audit: type=1334 audit(1663532330.893:2180): prog-id=0 op=UNLOAD
[1807627.274368] audit: type=1334 audit(1663532330.893:2181): prog-id=0 op=UNLOAD
[1815198.805610] audit: type=1334 audit(1663539902.431:2182): prog-id=979 op=LOAD
[1815198.806052] audit: type=1334 audit(1663539902.431:2183): prog-id=980 op=LOAD
[1815200.513426] audit: type=1334 audit(1663539904.139:2184): prog-id=981 op=LOAD
[1815200.513983] audit: type=1334 audit(1663539904.139:2185): prog-id=982 op=LOAD
[1815230.596119] audit: type=1334 audit(1663539934.219:2186): prog-id=0 op=UNLOAD
[1815230.596179] audit: type=1334 audit(1663539934.219:2187): prog-id=0 op=UNLOAD
[1815232.175310] audit: type=1334 audit(1663539935.798:2188): prog-id=0 op=UNLOAD
[1815232.176512] audit: type=1334 audit(1663539935.799:2189): prog-id=0 op=UNLOAD
[1815577.407200] hassio: port 8(veth15ce972) entered blocking state
[1815577.407245] hassio: port 8(veth15ce972) entered disabled state
[1815577.407725] device veth15ce972 entered promiscuous mode
[1815577.407928] audit: type=1700 audit(1663540281.017:2190): dev=veth15ce972 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[1815577.408074] audit: type=1300 audit(1663540281.017:2190): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=4000caa1e0 a2=28 a3=0 items=0 ppid=1 pid=458 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null)
[1815577.408127] audit: type=1327 audit(1663540281.017:2190): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[1815580.499570] audit: type=1325 audit(1663540284.108:2191): table=nat family=2 entries=0 op=xt_register pid=1334817 subj==unconfined comm="iptables"
[1815580.499632] audit: type=1300 audit(1663540284.108:2191): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fd01ead70 items=0 ppid=1334810 pid=1334817 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[1815580.499669] audit: type=1327 audit(1663540284.108:2191): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[1815580.587166] audit: type=1325 audit(1663540284.196:2192): table=filter family=2 entries=0 op=xt_register pid=1334820 subj==unconfined comm="iptables"
[1815580.587229] audit: type=1300 audit(1663540284.196:2192): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7feab92790 items=0 ppid=1334810 pid=1334820 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[1815580.587265] audit: type=1327 audit(1663540284.196:2192): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E
[1815580.613274] audit: type=1325 audit(1663540284.222:2193): table=nat family=2 entries=5 op=xt_replace pid=1334823 subj==unconfined comm="iptables"
[1815580.750838] eth0: renamed from veth825ed25
[1815580.761210] IPv6: ADDRCONF(NETDEV_CHANGE): veth15ce972: link becomes ready
[1815580.761495] hassio: port 8(veth15ce972) entered blocking state
[1815580.761521] hassio: port 8(veth15ce972) entered forwarding state
[1815613.655465] hassio: port 8(veth15ce972) entered disabled state
[1815613.656471] veth825ed25: renamed from eth0
[1815613.768913] hassio: port 8(veth15ce972) entered disabled state
[1815613.773515] device veth15ce972 left promiscuous mode
[1815613.773582] hassio: port 8(veth15ce972) entered disabled state
[1815613.773629] kauditd_printk_skb: 23 callbacks suppressed
[1815613.773638] audit: type=1700 audit(1663540317.373:2201): dev=veth15ce972 prom=0 old_prom=256 auid=4294967295 uid=0 gid=0 ses=4294967295
[1815613.783091] audit: type=1300 audit(1663540317.373:2201): arch=c00000b7 syscall=206 success=yes exit=32 a0=f a1=4000059340 a2=20 a3=0 items=0 ppid=1 pid=458 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj==unconfined key=(null)
[1815613.783156] audit: type=1327 audit(1663540317.373:2201): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[1815614.527504] audit: type=1325 audit(1663540318.135:2202): table=nat family=2 entries=15 op=xt_unregister pid=1332039 subj==unconfined comm="kworker/u8:2"
[1815614.527569] audit: type=1325 audit(1663540318.135:2203): table=filter family=2 entries=4 op=xt_unregister pid=1332039 subj==unconfined comm="kworker/u8:2"

System Health information

Optional Copy the full System Health in this text area. Can be found in the Configuration panel -> Settings -> Info. Use the copy icon on top right and choose For GitHub.

I can't find the icon in question.

Additional information

running wget https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb from the commandline does appear to download the file in question after a 302 redirect:

➜ ~ wget https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb --2022-09-18 18:46:56-- https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb Resolving github.com (github.com)... 140.82.114.4 Connecting to github.com (github.com)|140.82.114.4|:443... connected. HTTP request sent, awaiting response... 302 Found Location: https://objects.githubusercontent.com/github-production-release-asset-2e65be/115992009/b3e59470-dbe7-40ff-8e51-48100b2bd01d?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAIWNJYAX4CSVEH53A%2F20220918%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20220918T234657Z&X-Amz-Expires=300&X-Amz-Signature=789a8a3ddd15654f18f34399d39ce6a62a228e39cd276d427fb83aeb6d9b1e26&X-Amz-SignedHeaders=host&actor_id=0&key_id=0&repo_id=115992009&response-content-disposition=attachment%3B%20filename%3Dhaos_rpi3-64-9.0.raucb&response-content-type=application%2Foctet-stream [following] --2022-09-18 18:46:58-- https://objects.githubusercontent.com/github-production-release-asset-2e65be/115992009/b3e59470-dbe7-40ff-8e51-48100b2bd01d?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAIWNJYAX4CSVEH53A%2F20220918%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20220918T234657Z&X-Amz-Expires=300&X-Amz-Signature=789a8a3ddd15654f18f34399d39ce6a62a228e39cd276d427fb83aeb6d9b1e26&X-Amz-SignedHeaders=host&actor_id=0&key_id=0&repo_id=115992009&response-content-disposition=attachment%3B%20filename%3Dhaos_rpi3-64-9.0.raucb&response-content-type=application%2Foctet-stream Resolving objects.githubusercontent.com (objects.githubusercontent.com)... 185.199.111.133, 185.199.110.133, 185.199.109.133, ... Connecting to objects.githubusercontent.com (objects.githubusercontent.com)|185.199.111.133|:443... connected. HTTP request sent, awaiting response... 200 OK Length: 123381132 (118M) [application/octet-stream] Saving to: 'haos_rpi3-64-9.0.raucb'

Uthrom avatar Sep 19 '22 00:09 Uthrom 

DEBU[2305] Response body="{"result": "error", "message": "Can't fetch OTA update from https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb: Response payload is not completed"}" fields.time=38m25.219007174s headers="map[Content-Length:[192] Content-Type:[application/json; charset=utf-8] Da

Can you donwload that file from your computer (in the same network)? https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-64-9.0.raucb

Maybe a temporary GitHub outage (in your area)? Is this still happening?

agners avatar Sep 19 '22 08:09 agners

Using wget, both form my machine and the RPi, works (trace shown in the additional information section).

I tried the upgrade several times throughout the day, as it took almost 30 minutes to fail each time.

Uthrom avatar Sep 19 '22 10:09 Uthrom

Is internet connectivity considered good according to ha network info (host_internet and supervisor_internet?

agners avatar Sep 19 '22 12:09 agners

Is internet connectivity considered good according to ha network info (host_internet and supervisor_internet?

➜ ~ ha network info | grep internet host_internet: true supervisor_internet: true

I'm kicking off another update, I'll report back what happens.

Uthrom avatar Sep 19 '22 12:09 Uthrom

Ok. I'll transfer this to Supervisor, as the download seems to fail which is run from the Supervisor.

agners avatar Sep 19 '22 12:09 agners

I have i similar problem. I try to install a new system on ESXi and a get that it can't download a new supervisor. I've tried both HAOS 8.5 and 9.0 and all a get is the errors below:

22-09-18 17:47:09 INFO (SyncWorker_0) [supervisor.docker.interface] Updating image ghcr.io/home-assistant/qemux86-64-homeassistant:landingpage to ghcr.io/home-assistant/qemux86-64-homeassistant:2022.9.4
22-09-18 17:47:09 INFO (SyncWorker_0) [supervisor.docker.interface] Downloading docker image ghcr.io/home-assistant/qemux86-64-homeassistant with tag 2022.9.4.
22-09-18 17:52:44 ERROR (SyncWorker_0) [supervisor.docker.interface] Can't install ghcr.io/home-assistant/qemux86-64-homeassistant:2022.9.4: 500 Server Error for http+docker://localhost/v1.41/images/create?tag=2022.9.4&fromImage=ghcr.io%2Fhome-assistant%2Fqemux86-64-homeassistant&platform=linux%2Famd64: Internal Server Error ("Get "https://ghcr.io/v2/": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)")
22-09-18 17:52:44 WARNING (MainThread) [supervisor.homeassistant.core] Error on Home Assistant installation. Retry in 30sec

They just keep coming iver and over again. And when I monitor the network trafic on ESXi for that vitrual machine, I can see some network trafic but very slow.

I've tried this on both an ESXi server and on Virtual Box on windows and get the same errors.

Should I open a new issue or is this to any help to this isssue?

juppe1969 avatar Sep 19 '22 16:09 juppe1969

+1 I have exact same error as @Uthrom has described.. My inet connection is fine and i can easily download the package using wget

supervisor log: 22-09-22 11:13:05 ERROR (MainThread) [supervisor.os.manager] Can't fetch OTA update from https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-9.0.raucb: Response payload is not completed

wget:

wget https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-9.0.raucb --2022-09-22 11:21:04-- https://github.com/home-assistant/operating-system/releases/download/9.0/haos_rpi3-9.0.raucb Resolving github.com (github.com)... 140.82.121.4 Connecting to github.com (github.com)|140.82.121.4|:443... connected. HTTP request sent, awaiting response... 302 Found Location: https://objects.githubusercontent.com/github-production-release-asset-2e65be/115992009/7217f59f-1bd0-4697-9efe-acb61a35aa96?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAIWNJYAX4CSVEH53A%2F20220922%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20220922T012105Z&X-Amz-Expires=300&X-Amz-Signature=8d5809e4e738c671d6612e5106925128f1b37c91ce66b9762e6fede8f07bd44a&X-Amz-SignedHeaders=host&actor_id=0&key_id=0&repo_id=115992009&response-content-disposition=attachment%3B%20filename%3Dhaos_rpi3-9.0.raucb&response-content-type=application%2Foctet-stream [following] --2022-09-22 11:21:05-- https://objects.githubusercontent.com/github-production-release-asset-2e65be/115992009/7217f59f-1bd0-4697-9efe-acb61a35aa96?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAIWNJYAX4CSVEH53A%2F20220922%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20220922T012105Z&X-Amz-Expires=300&X-Amz-Signature=8d5809e4e738c671d6612e5106925128f1b37c91ce66b9762e6fede8f07bd44a&X-Amz-SignedHeaders=host&actor_id=0&key_id=0&repo_id=115992009&response-content-disposition=attachment%3B%20filename%3Dhaos_rpi3-9.0.raucb&response-content-type=application%2Foctet-stream Resolving objects.githubusercontent.com (objects.githubusercontent.com)... 185.199.111.133, 185.199.110.133, 185.199.109.133, ... Connecting to objects.githubusercontent.com (objects.githubusercontent.com)|185.199.111.133|:443... connected. HTTP request sent, awaiting response... 200 OK Length: 120800652 (115M) [application/octet-stream] Saving to: ‘haos_rpi3-9.0.raucb.1’

haos_rpi3-9.0.raucb.1 4%[=> ] 4,96M 1,27MB/s eta 94s ^C

rus-ik avatar Sep 22 '22 01:09 rus-ik

Obviously it magically resolved itself, and nobody knows what happened.

Uthrom avatar Sep 26 '22 22:09 Uthrom