hassio-google-drive-backup: "The Supervisor Returned an Unexpected Response" for larger snapshots on 32 bit arm platforms (eg raspberry pi's)

Hi, form the latest update of HA (0.114.0) seems that the addon is not working. I’ve already tried full restart and physical restart, nothing works like before with Hassio Google Drive Backup. To avoid problems, I’ve already done the re-authentication with Google…

Description:
If you have anything else that could help explain what happened, click "Markdown" above and write it here.

Addon version: 0.101.1 Home Assistant Version: 0.114.1 Supervisor Version: 234 Architecture: armv7 Date: 2020-08-16T11:33:38.183750+00:00 Timezone: Europe/Rome Failure Time: 2020-08-16T11:32:41.752325+00:00 Last Good Sync: 2020-08-16T11:32:41.159116+00:00

Exception:

 addon/backup/util/asynchttpgetter.py:133 (read)
 /site-packages/aiohttp/streams.py:416 (readexactly)
aiohttp.client_exceptions.ClientPayloadError: Response payload is not completed
Whose handling caused:
 addon/backup/model/coordinator.py:148 (_sync)
 addon/backup/model/model.py:162 (sync)
 addon/backup/drive/drivesource.py:148 (save)
 addon/backup/drive/driverequests.py:244 (create)
 addon/backup/util/asynchttpgetter.py:140 (read)
backup.exceptions.exceptions.SupervisorUnexpectedError

Snapshots:

{
   "HomeAssistant": {
       "snapshots": 4,
       "retained": 0,
       "deletable": 4,
       "name": "HomeAssistant",
       "latest": "2020-08-16T00:58:14Z",
       "size": "8.7 GB"
   },
   "GoogleDrive": {
       "snapshots": 1,
       "retained": 0,
       "deletable": 1,
       "name": "GoogleDrive",
       "latest": "2020-08-15T00:53:45Z",
       "size": "2.2 GB"
   }
}
Config:
{
   "Setting.MAX_SNAPSHOTS_IN_GOOGLE_DRIVE": 1,
   "Setting.DAYS_BETWEEN_SNAPSHOTS": 1.0,
   "Setting.SNAPSHOT_NAME": "REDACTED",
   "Setting.SNAPSHOT_TIME_OF_DAY": "02:01"
}
Addon Logs:
08-16 13:24:32 INFO [backup.model.coordinator] Syncing Snapshots
08-16 13:24:32 DEBUG [backup.ha.harequests] Making Hassio request: http://hassio/snapshots
08-16 13:24:32 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/16qmeXtaTELJXzYj1Hbb0st1KU7Qqj31c/?fields=id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents&supportsAllDrives=true
08-16 13:24:33 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/?q=%2716qmeXtaTELJXzYj1Hbb0st1KU7Qqj31c%27+in+parents&fields=nextPageToken%2Cfiles%28id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%29&pageSize=100&supportsAllDrives=true&includeItemsFromAllDrives=true&corpora=allDrives
08-16 13:24:33 INFO [backup.drive.drivesource] Uploading 'Full Snapshot 16-08-2020 02:58:13' to Google Drive
08-16 13:24:33 DEBUG [backup.drive.driverequests] Attempting to resume a previosuly failed upload where we left off
08-16 13:24:33 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=AAANsUkqOGeX4hyb7I84vlBf1QcMdNTc0WhaNf2OMnEBdmSngJ21mr46h-QBwjpjzwdbCNxnRYpV8M5Kzsxo-yXJMSdMAPzGbA
08-16 13:24:33 DEBUG [backup.drive.driverequests] Resuming upload at byte 0 of 2352496640
08-16 13:24:33 ERROR [backup.model.coordinator] The supervisor gave an unexpected response
08-16 13:24:33 INFO [backup.model.coordinator] I'll try again in 5(ish) minutes
08-16 13:29:40 INFO [backup.model.coordinator] Syncing Snapshots
08-16 13:29:40 DEBUG [backup.ha.harequests] Making Hassio request: http://hassio/snapshots
08-16 13:29:40 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/16qmeXtaTELJXzYj1Hbb0st1KU7Qqj31c/?fields=id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents&supportsAllDrives=true
08-16 13:29:40 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/?q=%2716qmeXtaTELJXzYj1Hbb0st1KU7Qqj31c%27+in+parents&fields=nextPageToken%2Cfiles%28id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%29&pageSize=100&supportsAllDrives=true&includeItemsFromAllDrives=true&corpora=allDrives
08-16 13:29:40 INFO [backup.drive.drivesource] Uploading 'Full Snapshot 16-08-2020 02:58:13' to Google Drive
08-16 13:29:40 DEBUG [backup.drive.driverequests] Attempting to resume a previosuly failed upload where we left off
08-16 13:29:40 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=AAANsUkqOGeX4hyb7I84vlBf1QcMdNTc0WhaNf2OMnEBdmSngJ21mr46h-QBwjpjzwdbCNxnRYpV8M5Kzsxo-yXJMSdMAPzGbA
08-16 13:29:40 DEBUG [backup.drive.driverequests] Resuming upload at byte 0 of 2352496640
08-16 13:29:40 ERROR [backup.model.coordinator] The supervisor gave an unexpected response
08-16 13:29:40 INFO [backup.model.coordinator] I'll try again in 10(ish) minutes
08-16 13:32:41 INFO [backup.model.coordinator] Syncing Snapshots
08-16 13:32:41 DEBUG [backup.ha.harequests] Making Hassio request: http://hassio/snapshots
08-16 13:32:41 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/16qmeXtaTELJXzYj1Hbb0st1KU7Qqj31c/?fields=id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents&supportsAllDrives=true
08-16 13:32:41 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/?q=%2716qmeXtaTELJXzYj1Hbb0st1KU7Qqj31c%27+in+parents&fields=nextPageToken%2Cfiles%28id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%29&pageSize=100&supportsAllDrives=true&includeItemsFromAllDrives=true&corpora=allDrives
08-16 13:32:41 INFO [backup.drive.drivesource] Uploading 'Full Snapshot 16-08-2020 02:58:13' to Google Drive
08-16 13:32:41 DEBUG [backup.drive.driverequests] Attempting to resume a previosuly failed upload where we left off
08-16 13:32:41 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=AAANsUkqOGeX4hyb7I84vlBf1QcMdNTc0WhaNf2OMnEBdmSngJ21mr46h-QBwjpjzwdbCNxnRYpV8M5Kzsxo-yXJMSdMAPzGbA
08-16 13:32:41 DEBUG [backup.drive.driverequests] Resuming upload at byte 0 of 2352496640
08-16 13:32:41 ERROR [backup.model.coordinator] The supervisor gave an unexpected response
08-16 13:32:41 INFO [backup.model.coordinator] I'll try again in 21(ish) minutes
Supervisor Logs:
20-08-16 11:19:45 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
20-08-16 11:19:45 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
20-08-16 11:19:45 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
20-08-16 11:19:45 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
20-08-16 11:19:45 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
20-08-16 11:19:45 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
20-08-16 11:19:45 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
20-08-16 11:19:45 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
20-08-16 11:19:48 INFO (MainThread) [supervisor.api.security] /snapshots access from cebe7a76_hassio_google_drive_backup
20-08-16 11:19:50 INFO (MainThread) [supervisor.api.security] /snapshots/845f6036/download access from cebe7a76_hassio_google_drive_backup
20-08-16 11:19:50 INFO (MainThread) [supervisor.api.snapshots] Download snapshot 845f6036
20-08-16 11:20:30 INFO (MainThread) [supervisor.api.security] /snapshots access from cebe7a76_hassio_google_drive_backup
20-08-16 11:20:31 INFO (MainThread) [supervisor.api.security] /snapshots/845f6036/download access from cebe7a76_hassio_google_drive_backup
20-08-16 11:20:31 INFO (MainThread) [supervisor.api.snapshots] Download snapshot 845f6036
20-08-16 11:21:51 INFO (MainThread) [supervisor.api.security] /snapshots access from cebe7a76_hassio_google_drive_backup
20-08-16 11:21:52 INFO (MainThread) [supervisor.api.security] /snapshots/845f6036/download access from cebe7a76_hassio_google_drive_backup
20-08-16 11:21:52 INFO (MainThread) [supervisor.api.snapshots] Download snapshot 845f6036
20-08-16 11:24:32 INFO (MainThread) [supervisor.api.security] /snapshots access from cebe7a76_hassio_google_drive_backup
20-08-16 11:24:33 INFO (MainThread) [supervisor.api.security] /snapshots/845f6036/download access from cebe7a76_hassio_google_drive_backup
20-08-16 11:24:33 INFO (MainThread) [supervisor.api.snapshots] Download snapshot 845f6036
20-08-16 11:29:40 INFO (MainThread) [supervisor.api.security] /snapshots access from cebe7a76_hassio_google_drive_backup
20-08-16 11:29:40 INFO (MainThread) [supervisor.api.security] /snapshots/845f6036/download access from cebe7a76_hassio_google_drive_backup
20-08-16 11:29:40 INFO (MainThread) [supervisor.api.snapshots] Download snapshot 845f6036
20-08-16 11:29:54 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
20-08-16 11:29:54 INFO (MainThread) [supervisor.api.security] /core/logs access from cebe7a76_hassio_google_drive_backup
20-08-16 11:32:41 INFO (MainThread) [supervisor.api.security] /snapshots access from cebe7a76_hassio_google_drive_backup
20-08-16 11:32:41 INFO (MainThread) [supervisor.api.security] /snapshots/845f6036/download access from cebe7a76_hassio_google_drive_backup
20-08-16 11:32:41 INFO (MainThread) [supervisor.api.snapshots] Download snapshot 845f6036
20-08-16 11:33:38 INFO (MainThread) [supervisor.api.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup

Home Assistant Core Logs:
2020-08-16 13:29:50 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.camera is taking over 10 seconds
2020-08-16 13:29:50 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:29:50 WARNING (SyncWorker_58) [homeassistant.components.tplink.light] Could not read data for 10.0.0.15: Communication error
2020-08-16 13:29:54 WARNING (MainThread) [homeassistant.config_entries] Config entry for upnp not ready yet. Retrying in 80 seconds
2020-08-16 13:30:00 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:30:05 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:30:05 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.camera is taking over 10 seconds
2020-08-16 13:30:15 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:30:20 WARNING (MainThread) [homeassistant.components.sensor] Updating mitemp_bt sensor took longer than the scheduled update interval 0:00:30
2020-08-16 13:30:25 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:30:35 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:30:45 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:30:50 WARNING (MainThread) [homeassistant.components.sensor] Updating mitemp_bt sensor took longer than the scheduled update interval 0:00:30
2020-08-16 13:30:51 WARNING (SyncWorker_63) [homeassistant.components.mitemp_bt.sensor] Polling error 
2020-08-16 13:30:51 WARNING (SyncWorker_53) [homeassistant.components.mitemp_bt.sensor] Polling error Could not read data from Mi Temp sensor 58:2D:34:31:2E:C7
2020-08-16 13:30:55 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:31:01 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.primo_piano_temp_battery is taking over 10 seconds
2020-08-16 13:31:05 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:31:15 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:31:20 WARNING (MainThread) [homeassistant.components.sensor] Updating mitemp_bt sensor took longer than the scheduled update interval 0:00:30
2020-08-16 13:31:24 WARNING (MainThread) [homeassistant.config_entries] Config entry for upnp not ready yet. Retrying in 80 seconds
2020-08-16 13:31:25 WARNING (MainThread) [homeassistant.components.light] Updating tplink light took longer than the scheduled update interval 0:00:05
2020-08-16 13:31:50 WARNING (MainThread) [homeassistant.components.sensor] Updating mitemp_bt sensor took longer than the scheduled update interval 0:00:30
2020-08-16 13:32:20 WARNING (MainThread) [homeassistant.components.sensor] Updating mitemp_bt sensor took longer than the scheduled update interval 0:00:30
2020-08-16 13:32:25 WARNING (MainThread) [frontend.js.latest.202008110] Icon mdi:file-document-box-outline was renamed to mdi:text-box-outline, please change your config, it will be removed in version 0.115.
2020-08-16 13:32:50 WARNING (MainThread) [homeassistant.components.sensor] Updating mitemp_bt sensor took longer than the scheduled update interval 0:00:30
2020-08-16 13:32:52 WARNING (SyncWorker_51) [homeassistant.components.mitemp_bt.sensor] Polling error 
2020-08-16 13:32:54 WARNING (MainThread) [homeassistant.config_entries] Config entry for upnp not ready yet. Retrying in 80 seconds
2020-08-16 13:33:30 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.piano_terra_temp_temperature is taking over 10 seconds

About this issue

  • Original URL
  • State: closed
  • Created 4 years ago
  • Comments: 46 (17 by maintainers)

Most upvoted comments

Ah looks like this morning they pinned it down to a bug in python, which they’ve patched for a future release of the supervisor. I’m not sure how fast their release cycle is, but a fix is in the works.

I’ll be adding it in the next release.