hassio-google-drive-backup icon indicating copy to clipboard operation
hassio-google-drive-backup copied to clipboard

Published 20 hours ago • verified publisher icon sabeechen

Connection reset by peer when trying to sync backups

Open babuscam opened this issue 3 years ago • 11 comments

Hi I am running the HA supervisor in docker on a raspberry pi 4. I have an issue with the Home Assistant Google Drive Backup addon, when I am trying to sync with google drive I am getting this error "An unexpected error occurred: [Errno 104] Connection reset by peer". I got the same error many times when I tried to add the repo for the "Home Assistant Community Addons", but somehow I managed to add that repo (idk how, but I did). So I think this error is not related to the addon but somehow to the HA. I have to mention that this error doesn't appear every time, sometimes the backups are uploading without any issue. Does anyone know how to solve this or encountered it before?

Addon version: 0.105.2 Home Assistant Version: 2021.11.1 Supervisor Version: 2021.10.8 Supervisor Channel: stable Hassos Version: None Docker Version: 20.10.10 Architecture: armv7 Machine: raspberrypi4 Date: 2021-11-14T17:41:31.218102+00:00 Timezone: Europe/Bucharest Failure Time: 2021-11-14T17:35:12.014321+00:00 Last Good Sync: 2021-11-14T17:35:10.412932+00:00

Exception:

 addon/backup/model/coordinator.py:176 (_sync)
 addon/backup/model/model.py:204 (sync)
 addon/backup/drive/drivesource.py:179 (save)
 addon/backup/drive/driverequests.py:280 (create)
 addon/backup/drive/driverequests.py:349 (retryRequest)
 addon/backup/creds/driverequester.py:30 (request)
 addon/backup/tracing_session.py:35 (_request)
 /site-packages/aiohttp/client.py:544 (_request)
 /site-packages/aiohttp/client_reqrep.py:890 (start)
 /site-packages/aiohttp/streams.py:604 (read)
aiohttp.client_exceptions.ClientOSError: [Errno 104] Connection reset by peer

Backups:

{
   "HomeAssistant": {
       "backups": 4,
       "retained": 0,
       "deletable": 4,
       "name": "HomeAssistant",
       "title": "Home Assistant",
       "latest": "2021-11-14T17:15:35Z",
       "max": 4,
       "enabled": true,
       "icon": "home-assistant",
       "ignored": 0,
       "size": "13.1 MB",
       "ignored_size": "0.0 B",
       "free_space": "197.1 GB"
   },
   "GoogleDrive": {
       "backups": 4,
       "retained": 0,
       "deletable": 4,
       "name": "GoogleDrive",
       "title": "Google Drive",
       "latest": "2021-11-13T19:58:24Z",
       "max": 4,
       "enabled": true,
       "icon": "google-drive",
       "ignored": 0,
       "size": "6.4 MB",
       "ignored_size": "0.0 B",
       "free_space": "13.3 GB"
   }
}
Config:
{}
Addon Logs:
11-14 19:26:28 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/supervisor/info
11-14 19:26:28 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/backups
11-14 19:26:28 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/1FrWwGsGDZ7zqr7HpSG3N0d8CykU88OIf/?fields=id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%2CdriveId&supportsAllDrives=true
11-14 19:26:28 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/about?fields=storageQuota
11-14 19:26:28 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/?q=%271FrWwGsGDZ7zqr7HpSG3N0d8CykU88OIf%27+in+parents&fields=nextPageToken%2Cfiles%28id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%2CdriveId%29&pageSize=100&supportsAllDrives=true&includeItemsFromAllDrives=true&corpora=allDrives
11-14 19:26:29 INFO [backup.drive.drivesource] Uploading 'Integrated all Philips bulbs and Mi Switches' to Google Drive
11-14 19:26:29 DEBUG [backup.drive.driverequests] Attempting to resume a previously failed upload where we left off
11-14 19:26:29 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=ADPycdsmSKkDBo4MlrwkDCqQgaW0RyCHsJvaOwLoSC6CXjIl_44NRFUhCQHQKECD8lDzXrW7oo7pLHHEv43m0cTMfbQ
11-14 19:26:29 DEBUG [backup.drive.driverequests] Resuming upload at byte 5767168 of 8151040
11-14 19:26:29 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
11-14 19:26:29 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=ADPycdsmSKkDBo4MlrwkDCqQgaW0RyCHsJvaOwLoSC6CXjIl_44NRFUhCQHQKECD8lDzXrW7oo7pLHHEv43m0cTMfbQ
11-14 19:26:29 ERROR [backup.model.coordinator] 
 addon/backup/model/coordinator.py:176 (_sync)
 addon/backup/model/model.py:204 (sync)
 addon/backup/drive/drivesource.py:179 (save)
 addon/backup/drive/driverequests.py:280 (create)
 addon/backup/drive/driverequests.py:349 (retryRequest)
 addon/backup/creds/driverequester.py:30 (request)
 addon/backup/tracing_session.py:35 (_request)
 /site-packages/aiohttp/client.py:544 (_request)
 /site-packages/aiohttp/client_reqrep.py:890 (start)
 /site-packages/aiohttp/streams.py:604 (read)
aiohttp.client_exceptions.ClientOSError: [Errno 104] Connection reset by peer

11-14 19:26:29 INFO [backup.model.coordinator] I'll try again in an hour
11-14 19:35:10 INFO [backup.model.coordinator] Syncing Backups
11-14 19:35:10 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/supervisor/info
11-14 19:35:10 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/backups
11-14 19:35:10 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/1FrWwGsGDZ7zqr7HpSG3N0d8CykU88OIf/?fields=id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%2CdriveId&supportsAllDrives=true
11-14 19:35:10 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/about?fields=storageQuota
11-14 19:35:11 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/?q=%271FrWwGsGDZ7zqr7HpSG3N0d8CykU88OIf%27+in+parents&fields=nextPageToken%2Cfiles%28id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%2CdriveId%29&pageSize=100&supportsAllDrives=true&includeItemsFromAllDrives=true&corpora=allDrives
11-14 19:35:11 INFO [backup.drive.drivesource] Uploading 'Integrated all Philips bulbs and Mi Switches' to Google Drive
11-14 19:35:11 DEBUG [backup.drive.driverequests] Attempting to resume a previously failed upload where we left off
11-14 19:35:11 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=ADPycdsmSKkDBo4MlrwkDCqQgaW0RyCHsJvaOwLoSC6CXjIl_44NRFUhCQHQKECD8lDzXrW7oo7pLHHEv43m0cTMfbQ
11-14 19:35:11 DEBUG [backup.drive.driverequests] Resuming upload at byte 5767168 of 8151040
11-14 19:35:11 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
11-14 19:35:11 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=ADPycdsmSKkDBo4MlrwkDCqQgaW0RyCHsJvaOwLoSC6CXjIl_44NRFUhCQHQKECD8lDzXrW7oo7pLHHEv43m0cTMfbQ
11-14 19:35:11 DEBUG [backup.drive.drivesource] Uploading Integrated all Philips bulbs and Mi Switches 73.97%
11-14 19:35:11 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
11-14 19:35:11 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=ADPycdsmSKkDBo4MlrwkDCqQgaW0RyCHsJvaOwLoSC6CXjIl_44NRFUhCQHQKECD8lDzXrW7oo7pLHHEv43m0cTMfbQ
11-14 19:35:12 ERROR [backup.model.coordinator] 
 addon/backup/model/coordinator.py:176 (_sync)
 addon/backup/model/model.py:204 (sync)
 addon/backup/drive/drivesource.py:179 (save)
 addon/backup/drive/driverequests.py:280 (create)
 addon/backup/drive/driverequests.py:349 (retryRequest)
 addon/backup/creds/driverequester.py:30 (request)
 addon/backup/tracing_session.py:35 (_request)
 /site-packages/aiohttp/client.py:544 (_request)
 /site-packages/aiohttp/client_reqrep.py:890 (start)
 /site-packages/aiohttp/streams.py:604 (read)
aiohttp.client_exceptions.ClientOSError: [Errno 104] Connection reset by peer

11-14 19:35:12 INFO [backup.model.coordinator] I'll try again in an hour
Supervisor Logs:
[32m21-11-14 19:21:45 INFO (MainThread) [supervisor.api.backups] Downloading backup cf7ffd5a[0m
[32m21-11-14 19:22:05 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:22:05 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:22:06 INFO (MainThread) [supervisor.api.middleware.security] /backups/cf7ffd5a/download access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:22:06 INFO (MainThread) [supervisor.api.backups] Downloading backup cf7ffd5a[0m
[32m21-11-14 19:22:06 INFO (MainThread) [supervisor.api.middleware.security] /backups/cf7ffd5a/download access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:22:06 INFO (MainThread) [supervisor.api.backups] Downloading backup cf7ffd5a[0m
[32m21-11-14 19:24:19 INFO (MainThread) [supervisor.ingress] Update Ingress as panel for cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:25:17 INFO (MainThread) [supervisor.backups.manager] Found 4 backup files[0m
[32m21-11-14 19:25:17 INFO (MainThread) [supervisor.backups.manager] Found 4 backup files[0m
[32m21-11-14 19:26:28 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:26:28 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:26:29 INFO (MainThread) [supervisor.api.middleware.security] /backups/cf7ffd5a/download access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:26:29 INFO (MainThread) [supervisor.api.backups] Downloading backup cf7ffd5a[0m
[32m21-11-14 19:26:29 INFO (MainThread) [supervisor.api.middleware.security] /backups/cf7ffd5a/download access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:26:29 INFO (MainThread) [supervisor.api.backups] Downloading backup cf7ffd5a[0m
[32m21-11-14 19:26:32 INFO (MainThread) [supervisor.api.middleware.security] /addons/a0d7b954_ssh/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:26:32 INFO (MainThread) [supervisor.api.middleware.security] /addons/cebe7a76_hassio_google_drive_backup/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:27:55 INFO (MainThread) [supervisor.backups.manager] Found 4 backup files[0m
[32m21-11-14 19:27:55 INFO (MainThread) [supervisor.backups.manager] Found 4 backup files[0m
[32m21-11-14 19:35:10 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:35:10 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:35:11 INFO (MainThread) [supervisor.api.middleware.security] /backups/cf7ffd5a/download access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:35:11 INFO (MainThread) [supervisor.api.backups] Downloading backup cf7ffd5a[0m
[32m21-11-14 19:35:11 INFO (MainThread) [supervisor.api.middleware.security] /backups/cf7ffd5a/download access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:35:11 INFO (MainThread) [supervisor.api.backups] Downloading backup cf7ffd5a[0m
[32m21-11-14 19:36:32 INFO (MainThread) [supervisor.api.middleware.security] /addons/cebe7a76_hassio_google_drive_backup/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:36:32 INFO (MainThread) [supervisor.api.middleware.security] /addons/a0d7b954_ssh/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m21-11-14 19:41:31 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup[0m
Home Assistant Core Logs:
[36m2021-11-13 21:52:58 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9A73:1:0x0500]: Updated alarm state: 1[0m
[36m2021-11-13 21:52:58 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9a73>, ep: 1, profile: 0x0104, cluster_id: 0x0406, data: b'18050a00001801'[0m
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
[finish] process exit code 0
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] done.
[services.d] starting services
[services.d] done.

page here

babuscam avatar Nov 14 '21 17:11 babuscam

After a power outage I've started having the same issue.

Tekno-man avatar Nov 16 '21 21:11 Tekno-man

Re-authorization in GDrive from menu helped me, but this is a workaround :(. Use re-auth for test

heejew avatar Nov 21 '21 21:11 heejew

I re-authed twice, but this issue is continuing over multiple days (started after a power failure too) any recommended way to debug?

ohadlevy avatar Mar 23 '22 14:03 ohadlevy

update, deleting the files on gdrive and trying again didnt help either.

ohadlevy avatar Mar 23 '22 14:03 ohadlevy

Honestly I don't know whats causing this, but I do have a theory. In the logs it looks like the first request succeeds, but then it fails after it attempts to upload a larger chunk. As @ohadlevy points out this could be because of a bug in how I handle resuming uploads but...I would expect a LOT of other things to fail if that were the case, including the first chunk it sends.

I had another user report that raising the MTU size on their router resolved the issue for them, which leads me to suspect that the size of uploads is somehow the problem. This might also be why a small chunk succeeds but a larger one does not. The OS should be handling breaking up the upload into smaller packets, but perhaps there is some complexity I don't understand in that process.

I just released a new version of the addon, 0.106.1. If anyone else is still having this problem, could you try updating to the latest version and changing the recently added configuration option "Maximum Drive Upload Chunk Size" to its minimum value, "256 kb"? You'll find it at the bottom of the Configuration menu of the add-on web-ui in the latest version under the "Uncommon Options" heading.

That will make your uploads slow, but if it also fixes the problem it gives me a really good clue about what the problem is.

sabeechen avatar Mar 23 '22 22:03 sabeechen

accidental closure

sabeechen avatar Mar 23 '22 22:03 sabeechen

@sabeechen I've upgraded to 106.2 and it suddenly worked (without changing the chunk size), I wonder if this was due to a problematic local backup that might expired already? in either case, it seems to be working now - thank you

ohadlevy avatar Mar 24 '22 10:03 ohadlevy

Well I'm glad its working but the transience of it is frustrating. If there is a bug in resuming uploads then a restart might have gotten past it since the upload cache isn't persisted through restarts.

Also if you're interested in debugging I have some instruction here. If you install the staging version of the addon you can add debugger_port: 1627 to the addon config to attach a remote debugger through VSCode to an instance of it running inside Home Assistant.

sabeechen avatar Mar 24 '22 17:03 sabeechen

I miss spoke, even with reduce chunk size (down to 256kb) it fails. I'll try debugging further, however, regarding restarts that didnt make any difference, e.g. right after restarting i saw:

03-27 13:31:25 INFO Starting server on port 8099
03-27 13:31:25 INFO Server started
03-27 13:31:25 INFO Syncing Backups
03-27 13:31:27 INFO Uploading 'Partial Backup 2022-03-27 02:30:00' to Google Drive
03-27 13:31:27 ERROR 
  addon/backup/model/coordinator.py:176 (_sync)
  addon/backup/model/model.py:214 (sync)
  addon/backup/drive/drivesource.py:179 (save)
  addon/backup/drive/driverequests.py:278 (create)
  addon/backup/drive/driverequests.py:350 (retryRequest)
  addon/backup/creds/driverequester.py:30 (request)
  addon/backup/tracing_session.py:35 (_request)
  /site-packages/aiohttp/client.py:559 (_request)
  /site-packages/aiohttp/client_reqrep.py:898 (start)
  /site-packages/aiohttp/streams.py:616 (read)
aiohttp.client_exceptions.ClientOSError: [Errno 104] Connection reset by peer

ohadlevy avatar Mar 27 '22 10:03 ohadlevy

Getting:

addon/backup/model/coordinator.py:177 (_sync) addon/backup/model/model.py:217 (sync) addon/backup/drive/drivesource.py:185 (save) addon/backup/drive/driverequests.py:278 (create) addon/backup/drive/driverequests.py:350 (retryRequest) addon/backup/creds/driverequester.py:30 (request) addon/backup/tracing_session.py:35 (_request) /site-packages/aiohttp/client.py:559 (_request) /site-packages/aiohttp/client_reqrep.py:898 (start) /site-packages/aiohttp/streams.py:616 (read) aiohttp.client_exceptions.ClientOSError: [Errno 104] Connection reset by peer

Addon never had any problems before today.

Tntdruid avatar May 09 '22 05:05 Tntdruid

Description:
I recently changed Google Accounts.  It worked for years on the old account, but I can't get a backup to complete on the new one.

Addon version: 0.107.2 Home Assistant Version: 2022.5.5 Supervisor Version: 2022.05.3 Supervisor Channel: stable Hassos Version: 8.1 Docker Version: 20.10.14 Architecture: amd64 Machine: qemux86-64 Date: 2022-05-29T23:57:48.015513+00:00 Timezone: America/Chicago Failure Time: 2022-05-29T23:57:33.530684+00:00 Last Good Sync: 2022-05-29T23:57:00.120334+00:00

Exception:

 addon/backup/creds/driverequester.py:30 (request)
 addon/backup/tracing_session.py:35 (_request)
 /site-packages/aiohttp/client.py:559 (_request)
 /site-packages/aiohttp/client_reqrep.py:898 (start)
 /site-packages/aiohttp/streams.py:616 (read)
aiohttp.client_exceptions.ClientOSError: [Errno 1] [SSL: SSLV3_ALERT_BAD_RECORD_MAC] sslv3 alert bad record mac (_ssl.c:2633)
Whose handling caused:
 addon/backup/model/coordinator.py:177 (_sync)
 addon/backup/model/model.py:217 (sync)
 addon/backup/drive/drivesource.py:185 (save)
 addon/backup/drive/driverequests.py:278 (create)
 addon/backup/drive/driverequests.py:350 (retryRequest)
 addon/backup/creds/driverequester.py:65 (request)
backup.exceptions.exceptions.GoogleUnexpectedError

Backups:

{
   "HomeAssistant": {
       "backups": 1,
       "retained": 0,
       "deletable": 1,
       "name": "HomeAssistant",
       "title": "Home Assistant",
       "latest": "2022-05-29T14:36:13Z",
       "max": 4,
       "enabled": true,
       "icon": "home-assistant",
       "ignored": 0,
       "detail": "",
       "size": "2.0 GB",
       "ignored_size": "0.0 B",
       "free_space": "10.8 GB"
   },
   "GoogleDrive": {
       "backups": 0,
       "retained": 0,
       "deletable": 0,
       "name": "GoogleDrive",
       "title": "Google Drive",
       "latest": null,
       "max": 4,
       "enabled": true,
       "icon": "google-drive",
       "ignored": 0,
       "detail": "[email protected]",
       "size": "0.0 B",
       "ignored_size": "0.0 B",
       "free_space": "24.7 GB"
   }
}
Config:
{
   "Setting.SEND_ERROR_REPORTS": true
}
Addon Logs:
05-29 18:56:49 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/?q=%271TrJa47t8vsh1-j5wmvI6jV9mPHmyfeWG%27+in+parents&fields=nextPageToken%2Cfiles%28id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%2CdriveId%29&pageSize=100&supportsAllDrives=true&includeItemsFromAllDrives=true&corpora=allDrives
05-29 18:56:50 ERROR [backup.model.coordinator] A backup folder already exists.  Please visit the add-on Web UI to select where to backup.
05-29 18:56:50 INFO [backup.model.coordinator] I'll try again in an hour
05-29 18:56:51 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/addons/self/options
05-29 18:56:51 DEBUG [backup.model.syncer] Sync requested by UI Server
05-29 18:56:51 INFO [backup.model.coordinator] Syncing Backups
05-29 18:56:51 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/supervisor/info
05-29 18:56:51 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/backups
05-29 18:56:51 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/about?fields=storageQuota%2Cuser
05-29 18:56:51 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/?q=%271TrJa47t8vsh1-j5wmvI6jV9mPHmyfeWG%27+in+parents&fields=nextPageToken%2Cfiles%28id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%2CdriveId%29&pageSize=100&supportsAllDrives=true&includeItemsFromAllDrives=true&corpora=allDrives
05-29 18:56:52 ERROR [backup.model.coordinator] A backup folder already exists.  Please visit the add-on Web UI to select where to backup.
05-29 18:56:52 INFO [backup.model.coordinator] I'll try again in an hour
05-29 18:56:54 INFO [backup.debugworker] Sending error report (see settings to disable)
05-29 18:57:00 INFO [backup.model.coordinator] Syncing Backups
05-29 18:57:00 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/supervisor/info
05-29 18:57:00 DEBUG [backup.ha.harequests] Making Hassio request: http://supervisor/backups
05-29 18:57:00 INFO [backup.drive.folderfinder] Saving backup folder: 1TrJa47t8vsh1-j5wmvI6jV9mPHmyfeWG
05-29 18:57:00 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/about?fields=storageQuota%2Cuser
05-29 18:57:00 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/drive/v3/files/?q=%271TrJa47t8vsh1-j5wmvI6jV9mPHmyfeWG%27+in+parents&fields=nextPageToken%2Cfiles%28id%2Cname%2CappProperties%2Csize%2Ctrashed%2CmimeType%2CmodifiedTime%2Ccapabilities%2Cparents%2CdriveId%29&pageSize=100&supportsAllDrives=true&includeItemsFromAllDrives=true&corpora=allDrives
05-29 18:57:00 INFO [backup.drive.drivesource] Uploading 'Full Backup 2022-05-29 09:36:13' to Google Drive
05-29 18:57:00 DEBUG [backup.drive.driverequests] Starting a new upload session with Google Drive
05-29 18:57:00 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true
05-29 18:57:00 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
05-29 18:57:00 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=ADPycdvsNrOCHMIz5uC71gWs6Tr2B8k7S4JvBGy2buaBZd6DeGlhQinU5Ky4nHFjuBx9Vpbji-0BqmrzS-ATJPu9dPfw-pT75LS2
05-29 18:57:01 DEBUG [backup.drive.drivesource] Uploading Full Backup 2022-05-29 09:36:13 0.01%
05-29 18:57:01 DEBUG [backup.drive.driverequests] Sending 8388608 bytes to Google Drive
05-29 18:57:01 DEBUG [backup.drive.driverequests] Making Google Drive request: https://www.googleapis.com/upload/drive/v3/files/?uploadType=resumable&supportsAllDrives=true&upload_id=ADPycdvsNrOCHMIz5uC71gWs6Tr2B8k7S4JvBGy2buaBZd6DeGlhQinU5Ky4nHFjuBx9Vpbji-0BqmrzS-ATJPu9dPfw-pT75LS2
05-29 18:57:33 ERROR [backup.model.coordinator] Google gave an unexpected response
05-29 18:57:33 INFO [backup.model.coordinator] I'll try again in an hour
05-29 18:57:34 INFO [backup.debugworker] Sending error report (see settings to disable)
Supervisor Logs:
[32m22-05-29 18:56:53 INFO (MainThread) [supervisor.api.middleware.security] /addons/core_mariadb/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:56:53 INFO (MainThread) [supervisor.api.middleware.security] /addons/a0d7b954_tailscale/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:56:53 INFO (MainThread) [supervisor.api.middleware.security] /addons/45df7312_zigbee2mqtt/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:56:53 INFO (MainThread) [supervisor.api.middleware.security] /addons/a0d7b954_chrony/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:56:53 INFO (MainThread) [supervisor.api.middleware.security] /addons/5c53de3b_esphome/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:56:53 INFO (MainThread) [supervisor.api.middleware.security] /addons/a0d7b954_ssh/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:56:53 INFO (MainThread) [supervisor.api.middleware.security] /addons/a0d7b954_bitwarden/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:56:53 INFO (MainThread) [supervisor.api.middleware.security] /addons/a0d7b954_sonweb/icon access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:57:00 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:57:00 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:57:00 INFO (MainThread) [supervisor.api.middleware.security] /backups/04512d07/download access from cebe7a76_hassio_google_drive_backup[0m
[32m22-05-29 18:57:00 INFO (MainThread) [supervisor.api.backups] Downloading backup 04512d07[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'require_ssl' does not exist in the schema for Node-RED (a0d7b954_nodered)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'dark_mode' does not exist in the schema for Node-RED (a0d7b954_nodered)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Unknown option 'base_topic' for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'external_converters' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'devices' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'groups' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'homeassistant' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'permit_join' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'advanced' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'device_options' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'blocklist' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'passlist' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'queue' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'frontend' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'experimental' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[33m22-05-29 18:57:12 WARNING (MainThread) [supervisor.addons.options] Option 'availability' does not exist in the schema for Zigbee2mqtt (45df7312_zigbee2mqtt)[0m
[32m22-05-29 18:57:48 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup[0m
Home Assistant Core Logs:
[33m2022-05-29 18:53:39 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:53:54 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:54:09 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:54:22 WARNING (MainThread) [homeassistant.components.apple_tv] Connection lost to Apple TV "Living Room"[0m
[33m2022-05-29 18:54:24 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[31m2022-05-29 18:54:27 ERROR (MainThread) [homeassistant.components.shelly] Timeout fetching shelly1-9F5AA5 data[0m
[31m2022-05-29 18:54:37 ERROR (MainThread) [homeassistant.components.flux_led.coordinator] Error fetching 10.10.12.120 data: 10.10.12.120: Bulb not responding, too soon to retry[0m
[33m2022-05-29 18:54:39 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:54:54 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:55:00 WARNING (MainThread) [homeassistant.helpers.service] Unable to find referenced entities light.jane_s_nightlight[0m
[33m2022-05-29 18:55:09 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:55:24 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:55:39 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:55:54 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:56:09 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:56:24 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:56:39 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:56:54 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:57:09 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:57:13 WARNING (MainThread) [homeassistant.components.apple_tv] Connection lost to Apple TV "Living Room"[0m
[31m2022-05-29 18:57:19 ERROR (MainThread) [homeassistant.components.alexa.state_report] Timeout sending report to Alexa for switch.jane_s_white_noise_machine_2[0m
[31m2022-05-29 18:57:19 ERROR (MainThread) [homeassistant.components.alexa.state_report] Timeout sending report to Alexa for switch.basement_entrance[0m
[31m2022-05-29 18:57:19 ERROR (MainThread) [homeassistant.components.alexa.state_report] Timeout sending report to Alexa for switch.kitchen_downlights[0m
[31m2022-05-29 18:57:19 ERROR (MainThread) [homeassistant.components.alexa.state_report] Timeout sending report to Alexa for light.fridge_notification_light[0m
[31m2022-05-29 18:57:19 ERROR (MainThread) [homeassistant.components.alexa.state_report] Timeout sending report to Alexa for switch.roland_s_white_noise_machine[0m
[31m2022-05-29 18:57:19 ERROR (MainThread) [homeassistant.components.alexa.state_report] Timeout sending report to Alexa for switch.garage_lights[0m
[31m2022-05-29 18:57:24 ERROR (MainThread) [homeassistant.components.alexa.state_report] Timeout sending report to Alexa for light.kitchen_main_lights_dimmer[0m
[33m2022-05-29 18:57:24 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m
[33m2022-05-29 18:57:39 WARNING (MainThread) [homeassistant.components.rest.sensor] JSON result was not a dictionary or list with 0th element a dictionary[0m

justinmartin avatar May 30 '22 00:05 justinmartin

I have the same issue. Started happening after a hard-poweroff, and possible filesystem corruption. Problem is, I need a current backup before I can reinstall the system...

lyricnz avatar Oct 23 '22 20:10 lyricnz

I think I may have found the source of this problem and released a fix in the latest version, 0.110.2. If anyone continues to see this problem after updating, could you re-open this and let me know?

Duplicate #808

sabeechen avatar Mar 24 '23 16:03 sabeechen

Hi I have the same issue as described above:

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

Addon version: 0.110.3 Home Assistant Version: 2023.4.2 Supervisor Version: 2023.04.0 Supervisor Channel: stable Hassos Version: 9.5 Docker Version: 20.10.22 Architecture: amd64 Machine: generic-x86-64 Date: 2023-04-12T19:39:17.133102+00:00 Timezone: Europe/Copenhagen Failure Time: 2023-04-12T19:38:08.539858+00:00 Last Good Sync: 2023-04-12T19:38:06.611200+00:00 Next Sync: 2023-04-12T19:39:28.539858+00:00 Next Backup: 2023-04-13T18:26:03.154997+00:00 Next Cache Warm: 2023-04-12T19:39:14.519989+00:00 Time Offset: 0.0

Exception:

 addon/backup/model/coordinator.py:198 (_sync)
 addon/backup/model/model.py:246 (sync)
 addon/backup/drive/drivesource.py:202 (save)
 addon/backup/drive/driverequests.py:291 (create)
 addon/backup/drive/driverequests.py:363 (retryRequest)
 addon/backup/creds/driverequester.py:29 (request)
 addon/backup/tracing_session.py:39 (_request)
 /site-packages/aiohttp/client.py:560 (_request)
 /site-packages/aiohttp/client_reqrep.py:899 (start)
 /site-packages/aiohttp/streams.py:616 (read)
aiohttp.client_exceptions.ClientOSError: [Errno 104] Connection reset by peer

Backups:

{
   "HomeAssistant": {
       "backups": 1,
       "retained": 0,
       "deletable": 1,
       "name": "HomeAssistant",
       "title": "Home Assistant",
       "latest": "2023-04-12T18:26:03Z",
       "max": 4,
       "enabled": true,
       "icon": "home-assistant",
       "ignored": 10,
       "detail": "",
       "size": "201.7 MB",
       "ignored_size": "115.1 MB",
       "free_space": "5.1 GB"
   },
   "GoogleDrive": {
       "backups": 0,
       "retained": 0,
       "deletable": 0,
       "name": "GoogleDrive",
       "title": "Google Drive",
       "latest": null,
       "max": 4,
       "enabled": true,
       "icon": "google-drive",
       "ignored": 0,
       "detail": "[email protected]",
       "size": "0.0 B",
       "ignored_size": "0.0 B",
       "free_space": "14.6 GB"
   }
}
Config:
{
   "Setting.DAYS_BETWEEN_BACKUPS": 1.0,
   "Setting.SEND_ERROR_REPORTS": true,
   "Setting.IGNORE_IPV6_ADDRESSES": true,
   "Setting.GOOGLE_DRIVE_TIMEOUT_SECONDS": 200.0
}
Addon Logs:
04-12 21:37:03 ERROR [backup.model.coordinator] Google gave an unexpected response
04-12 21:37:03 INFO [backup.model.coordinator] I'll try again in 20 seconds
04-12 21:37:06 DEBUG [backup.watcher] Checking backup source for changes...
04-12 21:37:23 DEBUG [backup.model.syncer] Sync requested by Coordinator
04-12 21:37:23 INFO [backup.model.coordinator] Syncing Backups
04-12 21:37:24 INFO [backup.drive.drivesource] Uploading 'heglund' to Google Drive
04-12 21:37:24 DEBUG [backup.drive.driverequests] Attempting to resume a previously failed upload where we left off
04-12 21:37:24 INFO [backup.watcher] A backup directory file was modified, we'll check for new backups soon.
04-12 21:37:24 DEBUG [backup.drive.driverequests] Resuming upload at byte 40370176 of 211507200
04-12 21:37:24 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
04-12 21:37:24 DEBUG [backup.drive.drivesource] Uploading heglund 19.21%
04-12 21:37:25 DEBUG [backup.drive.driverequests] Sending 4456448 bytes to Google Drive
04-12 21:37:26 ERROR [backup.model.coordinator] Google gave an unexpected response
04-12 21:37:26 INFO [backup.model.coordinator] I'll try again in 40 seconds
04-12 21:37:31 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
04-12 21:37:34 DEBUG [backup.watcher] Checking backup source for changes...
04-12 21:38:06 DEBUG [backup.model.syncer] Sync requested by Coordinator
04-12 21:38:06 INFO [backup.model.coordinator] Syncing Backups
04-12 21:38:07 INFO [backup.drive.drivesource] Uploading 'heglund' to Google Drive
04-12 21:38:07 DEBUG [backup.drive.driverequests] Attempting to resume a previously failed upload where we left off
04-12 21:38:07 INFO [backup.watcher] A backup directory file was modified, we'll check for new backups soon.
04-12 21:38:07 DEBUG [backup.drive.driverequests] Resuming upload at byte 42205184 of 211507200
04-12 21:38:07 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
04-12 21:38:07 DEBUG [backup.drive.drivesource] Uploading heglund 20.08%
04-12 21:38:07 DEBUG [backup.drive.driverequests] Sending 7340032 bytes to Google Drive
04-12 21:38:08 ERROR [backup.model.coordinator] 
 addon/backup/model/coordinator.py:198 (_sync)
 addon/backup/model/model.py:246 (sync)
 addon/backup/drive/drivesource.py:202 (save)
 addon/backup/drive/driverequests.py:291 (create)
 addon/backup/drive/driverequests.py:363 (retryRequest)
 addon/backup/creds/driverequester.py:29 (request)
 addon/backup/tracing_session.py:39 (_request)
 /site-packages/aiohttp/client.py:560 (_request)
 /site-packages/aiohttp/client_reqrep.py:899 (start)
 /site-packages/aiohttp/streams.py:616 (read)
aiohttp.client_exceptions.ClientOSError: [Errno 104] Connection reset by peer

04-12 21:38:08 INFO [backup.model.coordinator] I'll try again in 1 minutes, 20 seconds
04-12 21:38:16 INFO [backup.debugworker] Sending error report (see settings to disable)
04-12 21:38:17 DEBUG [backup.watcher] Checking backup source for changes...
04-12 21:38:32 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
Supervisor Logs:
[32m23-04-12 21:36:41 INFO (MainThread) [supervisor.api.middleware.security] /backups/1509a02f/download access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:36:41 INFO (MainThread) [supervisor.api.backups] Downloading backup 1509a02f[0m
[32m23-04-12 21:36:51 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:36:51 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:36:55 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:36:55 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:36:56 INFO (MainThread) [supervisor.api.middleware.security] /backups/1509a02f/download access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:36:56 INFO (MainThread) [supervisor.api.backups] Downloading backup 1509a02f[0m
[32m23-04-12 21:36:56 INFO (MainThread) [supervisor.api.middleware.security] /backups/1509a02f/download access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:36:56 INFO (MainThread) [supervisor.api.backups] Downloading backup 1509a02f[0m
[32m23-04-12 21:37:06 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:37:06 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:37:23 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:37:23 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:37:24 INFO (MainThread) [supervisor.api.middleware.security] /backups/1509a02f/download access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:37:24 INFO (MainThread) [supervisor.api.backups] Downloading backup 1509a02f[0m
[32m23-04-12 21:37:24 INFO (MainThread) [supervisor.api.middleware.security] /backups/1509a02f/download access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:37:24 INFO (MainThread) [supervisor.api.backups] Downloading backup 1509a02f[0m
[32m23-04-12 21:37:34 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:37:34 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:38:06 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:38:06 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:38:07 INFO (MainThread) [supervisor.api.middleware.security] /backups/1509a02f/download access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:38:07 INFO (MainThread) [supervisor.api.backups] Downloading backup 1509a02f[0m
[32m23-04-12 21:38:07 INFO (MainThread) [supervisor.api.middleware.security] /backups/1509a02f/download access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:38:07 INFO (MainThread) [supervisor.api.backups] Downloading backup 1509a02f[0m
[32m23-04-12 21:38:17 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:38:17 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m23-04-12 21:39:17 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup[0m
Home Assistant Core Logs:
s6-rc: info: service legacy-services: starting
services-up: info: copying legacy longrun home-assistant (no readiness notification)
s6-rc: info: service legacy-services successfully started
[33m2023-04-10 15:15:00.658 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant[0m
[2023-04-10 15:15:12,082] [tuya-openmq] error while get mqtt config
[31m2023-04-10 15:15:12.082 ERROR (Thread-2) [tuya_iot] error while get mqtt config[0m
[31m2023-04-10 15:15:12.087 ERROR (Thread-2) [root] Uncaught thread exception
Traceback (most recent call last):
 File "/usr/local/lib/python3.10/threading.py", line 1016, in _bootstrap_inner
   self.run()
 File "/usr/local/lib/python3.10/site-packages/tuya_iot/openmq.py", line 161, in run
   time.sleep(self.mq_config.expire_time - 60)
AttributeError: 'NoneType' object has no attribute 'expire_time'[0m
[33m2023-04-10 15:15:15.253 WARNING (MainThread) [aioesphomeapi.reconnect_logic] Can't connect to ESPHome API for wateresp32 @ 10.0.3.212: Error connecting to ('10.0.3.212', 6053): [Errno 113] Connect call failed ('10.0.3.212', 6053)[0m
[33m2023-04-10 15:15:19.324 WARNING (MainThread) [homeassistant.config_entries] Config entry 'UGW3' for upnp integration not ready yet: Device not discovered: uuid:22907665-a63e-4cc6-a592-9f90cd9808c1::urn:schemas-upnp-org:device:InternetGatewayDevice:1; Retrying in background[0m
[33m2023-04-10 15:15:21.397 WARNING (MainThread) [aioesphomeapi.reconnect_logic] Can't connect to ESPHome API for wateresp32 @ 10.0.3.212: Error connecting to ('10.0.3.212', 6053): [Errno 113] Connect call failed ('10.0.3.212', 6053)[0m
[33m2023-04-10 17:52:22.433 WARNING (MainThread) [homeassistant.helpers.service] Unable to find referenced entities switch.1000667dc5 or it is/they are currently not available[0m
[33m2023-04-11 12:00:00.138 WARNING (MainThread) [homeassistant.helpers.service] Unable to find referenced entities switch.1000667dc5 or it is/they are currently not available[0m
[31m2023-04-11 13:59:29.532 ERROR (MainThread) [snitun.multiplexer.core] Ping fails, no response from peer[0m
[33m2023-04-11 18:00:09.426 WARNING (MainThread) [homeassistant.helpers.service] Unable to find referenced entities switch.1000667dc5 or it is/they are currently not available[0m
[31m2023-04-11 18:32:38.052 ERROR (MainThread) [frontend.js.latest.202303091] :0:0 ResizeObserver loop completed with undelivered notifications.[0m
[31m2023-04-12 04:35:46.101 ERROR (MainThread) [homeassistant.components.fronius] Error fetching fronius_power_flow_10.0.3.214 data: Connection to Fronius device failed at http://10.0.3.214/solar_api/v1/GetPowerFlowRealtimeData.fcgi.[0m
[31m2023-04-12 04:35:49.173 ERROR (MainThread) [homeassistant.components.fronius] Error fetching fronius_meters_10.0.3.214 data: Connection to Fronius device failed at http://10.0.3.214/solar_api/v1/GetMeterRealtimeData.cgi?Scope=System.[0m
[31m2023-04-12 04:35:52.245 ERROR (MainThread) [homeassistant.components.fronius] Error fetching fronius_inverter_1_10.0.3.214 data: Connection to Fronius device failed at http://10.0.3.214/solar_api/v1/GetInverterRealtimeData.cgi?Scope=Device&DeviceId=1&DataCollection=CommonInverterData.[0m
[33m2023-04-12 11:30:00.112 WARNING (MainThread) [homeassistant.helpers.service] Unable to find referenced entities switch.1000667dc5 or it is/they are currently not available[0m
[33m2023-04-12 17:39:55.927 WARNING (MainThread) [homeassistant.components.websocket_api.http.connection] [140589328413360] Disconnected: Received non-Text message.[0m
[33m2023-04-12 17:42:03.458 WARNING (MainThread) [homeassistant.helpers.service] Unable to find referenced entities switch.1000667dc5 or it is/they are currently not available[0m
[33m2023-04-12 18:06:54.548 WARNING (MainThread) [homeassistant.components.websocket_api.http.connection] [140589379255600] Disconnected: Received non-Text message.[0m
[33m2023-04-12 21:20:00.187 WARNING (MainThread) [homeassistant.components.http.security_filter] Filtered a request with unsafe byte query string: /api/hassio_ingress/nm_zBZaBMSsTAP78ME9sv1W7MgDIujGdc_2_y3i0OYY/token?creds=++++++++++++++eyJjbGllbnRfaWQiOiAiOTMzOTQ0Mjg4MDE2LW4zNWdubjJqdWM3NnViN3U1MzI2bHMwaWFxOWRnamd1LmFwcHMuZ29vZ2xldXNlcmNvbnRlbnQuY29tIiwgInJlZnJlc2hfdG9rZW4iOiAiMS8vMDRpSnVrWFMxWFhoaUNnWUlBUkFBR0FRU053Ri1MOUlyeDhMc0Y3WkRueDhuMWpaclMyZ0FtdWlLSkpaM054Z2tySnotME5lcm1ZODJoRnRnVVItNHFSRGJ0ekJJc0lDS0hQWSIsICJhY2Nlc3NfdG9rZW4iOiAieWEyOS5hMEFlbDlzQ05xQmVoN1RqSHlJMk9lZk5JRE9uX3I2eEo3MnIzaW11ZW9jVXEtb3A5QWNKV2hKNzZPUDdtV0FFRVZjd2Y3bXhiUENGMXVaaDRDdU9rQVRHQ0E4eWM1WXJaMHRseWt0X3FlcUNadXIzVjBNcDQ4dXQtZ2o1QkJBWnRxcUpXRzFkNHB3T1RpTlUtNmxvZVQ0UHdoSGpCS2FDZ1lLQVdFU0FSQVNGUUY0dWRKaFhGMGgyUFVKd25GVkdTaERoalpYTVEwMTYzIiwgInRva2VuX2V4cGlyeSI6ICIyMDIzLTA0LTEyVDIwOjE4OjU0WiJ9%0A++++++++++&host=https%3A%2F%2F1iby68klm4lobykuvu8wo61aqhj0yf9b.ui.nabu.casa%2Fhassio%2Fstore[0m

lpheglund avatar Apr 12 '23 19:04 lpheglund

@lpheglund You're seeing a different issue, not the ssl error originally reported. Its likely caused by an unstable internet connection or network hardware that has trouble with large packets. Others have resolved this in the past by lowering the option "Maximum Drive Upload Chunk Size" near the bottom of the addon's setting page under "Uncommon Options". I'd start with the minimum (256kb), which will make backups slow, and then increase it so long as you don't run into problems.

sabeechen avatar Apr 21 '23 22:04 sabeechen

Thanks that actually helped. Thanks for getting back to me.

lpheglund avatar Apr 26 '23 17:04 lpheglund