search

sabeechen / hassio-google-drive-backup

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

Google drive Returned an Unexpected Response #890

Open ffm777 opened 1 year ago

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

Addon version: 0.111.1 Home Assistant Version: 2023.7.2 Supervisor Version: 2023.07.1 Supervisor Channel: stable Hassos Version: 10.3 Docker Version: 23.0.6 Architecture: amd64 Machine: qemux86-64 Date: 2023-07-16T18:27:33.222688+00:00 Timezone: Europe/Berlin Failure Time: 2023-07-16T18:27:13.003287+00:00 Last Good Sync: 2023-07-16T18:26:37.495050+00:00 Next Sync: 2023-07-16T20:27:13.003287+00:00 Next Backup: 2023-07-19T08:58:13.682707+00:00 Next Cache Warm: 2023-07-16T20:20:12.493446+00:00 Time Offset: 0.0

Exception:

  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 1] [SSL: SSLV3_ALERT_BAD_RECORD_MAC] sslv3 alert bad record mac (_ssl.c:2576)
Whose handling caused:
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:250 (sync)
  addon/backup/drive/drivesource.py:202 (save)
  addon/backup/drive/driverequests.py:300 (create)
  addon/backup/drive/driverequests.py:372 (retryRequest)
  addon/backup/creds/driverequester.py:68 (request)
backup.exceptions.exceptions.GoogleUnexpectedError

Backups:

 {
    "HomeAssistant": {
        "backups": 1,
        "retained": 0,
        "deletable": 1,
        "name": "HomeAssistant",
        "title": "Home Assistant",
        "latest": "2023-07-16T08:58:13Z",
        "max": 4,
        "enabled": true,
        "icon": "home-assistant",
        "ignored": 1,
        "detail": "",
        "size": "4.0 GB",
        "ignored_size": "20.5 kB"
    },
    "GoogleDrive": {
        "backups": 0,
        "retained": 0,
        "deletable": 0,
        "name": "GoogleDrive",
        "title": "Google Drive",
        "latest": null,
        "max": 4,
        "enabled": true,
        "icon": "google-drive",
        "ignored": 0,
        "detail": "carlito.kraemer@gmail.com",
        "size": "0.0 B",
        "ignored_size": "0.0 B",
        "free_space": "10.9 GB"
    }
}
Config:
 {
    "Setting.SEND_ERROR_REPORTS": true
}
Addon Logs:
 07-16 18:32:12 DEBUG [backup.drive.driverequests] Resuming upload at byte 431226880 of 4331642880
07-16 18:32:12 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
07-16 18:32:12 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-07-16 10:58:13 9.96%
07-16 18:32:12 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
07-16 18:32:18 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-07-16 10:58:13 10.20%
07-16 18:32:18 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
07-16 18:32:41 ERROR [backup.model.coordinator] Google gave an unexpected response
07-16 18:32:41 INFO [backup.model.coordinator] I'll try again in 2 hours
07-16 20:25:31 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
07-16 20:25:31 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
07-16 20:26:32 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
07-16 20:26:37 INFO [backup.model.coordinator] Syncing Backups
07-16 20:26:37 INFO [backup.drive.drivesource] Uploading 'Full Backup 2023-07-16 10:58:13' to Google Drive
07-16 20:26:37 DEBUG [backup.drive.driverequests] Attempting to resume a previously failed upload where we left off
07-16 20:26:37 DEBUG [backup.drive.driverequests] Resuming upload at byte 450887680 of 4331642880
07-16 20:26:37 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
07-16 20:26:38 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-07-16 10:58:13 10.42%
07-16 20:26:38 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
07-16 20:26:41 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-07-16 10:58:13 10.66%
07-16 20:26:41 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
07-16 20:26:44 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-07-16 10:58:13 10.90%
07-16 20:26:44 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
07-16 20:26:48 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-07-16 10:58:13 11.14%
07-16 20:26:48 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
07-16 20:26:51 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-07-16 10:58:13 11.38%
07-16 20:26:51 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
07-16 20:26:54 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-07-16 10:58:13 11.63%
07-16 20:26:55 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
07-16 20:27:13 ERROR [backup.model.coordinator] Google gave an unexpected response
07-16 20:27:13 INFO [backup.model.coordinator] I'll try again in 2 hours
Supervisor Logs:
 23-07-16 20:23:40 INFO (MainThread) [supervisor.addons] Add-on '9074a9fa_cloudflared' successfully updated
23-07-16 20:23:40 INFO (SyncWorker_5) [supervisor.docker.interface] Cleanup images: ['ghcr.io/brenner-tobias/cloudflared/amd64:4.1.9']
23-07-16 20:23:41 INFO (SyncWorker_0) [supervisor.docker.addon] Starting Docker add-on ghcr.io/brenner-tobias/cloudflared/amd64 with version 4.2.0
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/blakeblackshear/frigate-hass-addons repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/Akulatraxas/ha-modbusproxy repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/hassio repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/brenner-tobias/ha-addons repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/allenporter/stream-addons repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/evcc-io/hassio-addon repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/sabeechen/hassio-google-drive-backup repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
23-07-16 20:23:41 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
23-07-16 20:23:44 WARNING (SyncWorker_3) [supervisor.addons.validate] Add-on have full device access, and selective device access in the configuration. Please report this to the maintainer of Frigate (Full Access) Beta (0.12.0)
23-07-16 20:23:45 INFO (MainThread) [supervisor.api.middleware.security] /core/info access from 9074a9fa_cloudflared
23-07-16 20:23:46 WARNING (SyncWorker_0) [supervisor.addons.validate] Add-on have full device access, and selective device access in the configuration. Please report this to the maintainer of Frigate (Full Access) Beta (0.12.0)
23-07-16 20:23:46 INFO (MainThread) [supervisor.store] Loading add-ons from store: 85 all - 0 new - 0 remove
23-07-16 20:23:46 INFO (MainThread) [supervisor.store] Loading add-ons from store: 85 all - 0 new - 0 remove
23-07-16 20:24:03 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
23-07-16 20:24:04 INFO (MainThread) [supervisor.api.middleware.security] /core/logs access from cebe7a76_hassio_google_drive_backup
23-07-16 20:26:37 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
23-07-16 20:26:37 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup
23-07-16 20:26:37 INFO (MainThread) [supervisor.api.middleware.security] /backups/1416bdad/info access from cebe7a76_hassio_google_drive_backup
23-07-16 20:26:37 INFO (MainThread) [supervisor.api.middleware.security] /backups/f8961e6d/download access from cebe7a76_hassio_google_drive_backup
23-07-16 20:26:37 INFO (MainThread) [supervisor.api.backups] Downloading backup f8961e6d
23-07-16 20:26:37 INFO (MainThread) [supervisor.api.middleware.security] /backups/f8961e6d/download access from cebe7a76_hassio_google_drive_backup
23-07-16 20:26:37 INFO (MainThread) [supervisor.api.backups] Downloading backup f8961e6d
23-07-16 20:27:33 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
Home Assistant Core Logs:
 2023-07-16 20:22:00.369 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Error while executing automation automation.sonnenaufgang: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:22:08.706 ERROR (MainThread) [aioharmony.harmonyclient] 192.168.20.217: Unable to determine if XMPP is enabled: [Errno 113] Connect call failed ('192.168.20.217', 5222)
2023-07-16 20:23:00.363 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Sonnenaufgang: Error executing script. Error for wait_template at pos 2: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:23:00.366 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Error while executing automation automation.sonnenaufgang: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:23:32.076 ERROR (MainThread) [aioharmony.harmonyclient] 192.168.20.217: Unable to determine if XMPP is enabled: [Errno 113] Connect call failed ('192.168.20.217', 5222)
2023-07-16 20:24:00.363 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Sonnenaufgang: Error executing script. Error for wait_template at pos 2: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:24:00.365 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Error while executing automation automation.sonnenaufgang: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:24:55.586 ERROR (MainThread) [aioharmony.harmonyclient] 192.168.20.217: Unable to determine if XMPP is enabled: [Errno 113] Connect call failed ('192.168.20.217', 5222)
2023-07-16 20:25:00.362 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Sonnenaufgang: Error executing script. Error for wait_template at pos 2: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:25:00.364 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Error while executing automation automation.sonnenaufgang: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:26:00.364 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Sonnenaufgang: Error executing script. Error for wait_template at pos 2: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:26:00.367 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Error while executing automation automation.sonnenaufgang: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:26:19.026 ERROR (MainThread) [aioharmony.harmonyclient] 192.168.20.217: Unable to determine if XMPP is enabled: [Errno 113] Connect call failed ('192.168.20.217', 5222)
2023-07-16 20:27:00.363 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Sonnenaufgang: Error executing script. Error for wait_template at pos 2: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:27:00.366 ERROR (MainThread) [homeassistant.components.automation.sonnenaufgang] Error while executing automation automation.sonnenaufgang: In 'template' condition: ValueError: Template error: as_timestamp got invalid input 'unknown 07:10:00' when rendering template '{{0 < as_timestamp(states(sensor) if sensor != 'none' else states('sensor.date') ~ ' ' ~ manual_time) - as_timestamp(states('sensor.date_time_iso')) <= float(seconds) and states(check_entity) in ['unknown', 'on', 'home']}}' but no default was specified
2023-07-16 20:27:03.525 WARNING (MainThread) [custom_components.roborock.camera] Unable to retrieve map data
2023-07-16 20:27:07.770 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.garten] Error from stream worker: Error demuxing stream: [Errno 110] Operation timed out: 'rtsp://192.168.178.80:8554/garten'
2023-07-16 20:27:07.770 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.garage] Error from stream worker: Error demuxing stream: [Errno 110] Operation timed out: 'rtsp://192.168.178.80:8554/garage'
2023-07-16 20:27:07.813 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.stellplatz] Error from stream worker: Error demuxing stream: [Errno 110] Operation timed out: 'rtsp://192.168.178.80:8554/stellplatz'
2023-07-16 20:27:08.128 ERROR (MainThread) [homeassistant.components.xiaomi_miio] Timeout fetching Robbi Kraemer data
2023-07-16 20:27:08.526 WARNING (MainThread) [custom_components.roborock.camera] Unable to retrieve map data
2023-07-16 20:27:10.186 ERROR (MainThread) [homeassistant.components.shelly] Error fetching shellygas-483FDA4CD2E0 data: Error fetching data: DeviceConnectionError()
2023-07-16 20:27:10.484 ERROR (MainThread) [homeassistant.components.shelly] Error fetching shellyplug-s-C1487A data: Error fetching data: DeviceConnectionError()
2023-07-16 20:27:13.234 INFO (MainThread) [backoff] Backing off _do_read(...) for 1.0s (TimeoutError)
2023-07-16 20:27:13.526 WARNING (MainThread) [custom_components.roborock.camera] Unable to retrieve map data
2023-07-16 20:27:16.030 WARNING (MainThread) [roborock.api] [Errno 104] Connection reset by peer
2023-07-16 20:27:18.205 ERROR (MainThread) [homeassistant.components.shelly] Error fetching shellyplug-s-7AED42 data: Error fetching data: DeviceConnectionError()
2023-07-16 20:27:18.465 ERROR (MainThread) [homeassistant.components.shelly] Error fetching shellyplug-s-FDC95A data: Error fetching data: DeviceConnectionError()
2023-07-16 20:27:19.065 ERROR (MainThread) [homeassistant.components.shelly] Error fetching shellyplug-s-7ADF8F data: Error fetching data: DeviceConnectionError()
sabeechen commented 1 year ago

Its unfortunate to see this, I'd hoped I had resolved it in #808 as none of the users seeing it had reported back, its has been a persistent problem for some for a long time and seems to go away and come back randomly.

Do you have any additional information? Some thing that might help:

ffm777 commented 1 year ago

Unfortunately, I can not recall 100% when the problems started. I have used the add-on for years without any problems. It stopped working some weeks ago. I have the gut feeling that it started once I moved the backups onto my NAS. So basically when HA introduced the option to store backups on the NAS, I immediately implemented it. Since then the add-on was not working anymore. But I can't tell for sure whether it worked in the weeks before. But it could be that it was introduced back then.

It does not go away when restarting the add-on and also not when rebooting the machine. I have already tried these solutions. I have also checked that it is not a problem with my Pi-Hole. That was one of the things I thought could be the culprit. But it wasn't.

spartan117aut commented 1 year ago

I have the same problem. I've seen this message coming and going during the last months, but now the newest successfully uploaded backup is from 19.8.2023. Also, I am using a NFS share as backup location since it was introduced in homeassistant, but I'm not too confident to blame it on that either...

HomeAssistant runs in a VM on a Proxmox Host. Restart of the VM and the host itself did not help. Manual sync fails too.

Description:

Addon version: 0.111.1 Home Assistant Version: 2023.8.2 Supervisor Version: 2023.08.1 Supervisor Channel: stable Hassos Version: 10.2 Docker Version: 23.0.6 Architecture: amd64 Machine: qemux86-64 Date: 2023-09-04T17:51:04.854232+00:00 Timezone: Europe/Vienna Failure Time: 2023-09-04T17:32:09.232249+00:00 Last Good Sync: 2023-09-04T17:31:46.610296+00:00 Next Sync: 2023-09-04T19:32:09.232249+00:00 Next Backup: 2023-09-05T02:00:00+00:00 Next Cache Warm: 2023-09-04T19:20:57.138493+00:00 Time Offset: 0.0

Exception:

  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 1] [SSL: SSLV3_ALERT_BAD_RECORD_MAC] sslv3 alert bad record mac (_ssl.c:2576)
Whose handling caused:
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:250 (sync)
  addon/backup/drive/drivesource.py:202 (save)
  addon/backup/drive/driverequests.py:300 (create)
  addon/backup/drive/driverequests.py:372 (retryRequest)
  addon/backup/creds/driverequester.py:68 (request)
backup.exceptions.exceptions.GoogleUnexpectedError

Backups:

 {
    "HomeAssistant": {
        "backups": 8,
        "retained": 0,
        "deletable": 8,
        "name": "HomeAssistant",
        "title": "Home Assistant",
        "latest": "2023-09-04T03:18:12Z",
        "max": 8,
        "enabled": true,
        "icon": "home-assistant",
        "ignored": 10,
        "detail": "",
        "size": "7.5 GB",
        "ignored_size": "1.1 GB"
    },
    "GoogleDrive": {
        "backups": 8,
        "retained": 0,
        "deletable": 8,
        "name": "GoogleDrive",
        "title": "Google Drive",
        "latest": "2023-08-19T02:00:00Z",
        "max": 8,
        "enabled": true,
        "icon": "google-drive",
        "ignored": 0,
        "detail": "redacted",
        "size": "7.6 GB",
        "ignored_size": "0.0 B",
        "free_space": "45.8 GB"
    }
}
Config:
 {
    "Setting.MAX_BACKUPS_IN_HA": 8,
    "Setting.MAX_BACKUPS_IN_GOOGLE_DRIVE": 8,
    "Setting.DAYS_BETWEEN_BACKUPS": 1.0,
    "Setting.BACKUP_TIME_OF_DAY": "04:00",
    "Setting.BACKUP_PASSWORD": "REDACTED",
    "Setting.GENERATIONAL_DAYS": 2,
    "Setting.GENERATIONAL_WEEKS": 3,
    "Setting.GENERATIONAL_MONTHS": 3
}
Addon Logs:
 09-04 17:30:47 DEBUG [backup.model.syncer] Sync requested by Coordinator
09-04 17:30:47 INFO [backup.model.coordinator] Syncing Backups
09-04 17:30:48 INFO [backup.drive.drivesource] Uploading 'Full Backup 2023-09-04 05:18:12' to Google Drive
09-04 17:30:48 DEBUG [backup.drive.driverequests] Attempting to resume a previously failed upload where we left off
09-04 17:30:49 DEBUG [backup.drive.driverequests] Resuming upload at byte 235143168 of 861399040
09-04 17:30:49 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
09-04 17:30:49 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-09-04 05:18:12 27.33%
09-04 17:30:49 DEBUG [backup.drive.driverequests] Sending 8388608 bytes to Google Drive
09-04 17:30:56 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-09-04 05:18:12 28.30%
09-04 17:30:56 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
09-04 17:31:05 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-09-04 05:18:12 29.52%
09-04 17:31:05 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
09-04 17:31:14 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-09-04 05:18:12 30.74%
09-04 17:31:14 DEBUG [backup.drive.driverequests] Sending 10485760 bytes to Google Drive
09-04 17:31:46 ERROR [backup.model.coordinator] Google gave an unexpected response
09-04 17:31:46 INFO [backup.model.coordinator] I'll try again in 2 hours
09-04 18:18:05 INFO [backup.watcher] A backup directory file was modified, we'll check for new backups soon.
09-04 18:18:15 DEBUG [backup.watcher] Checking backup source for changes...
09-04 19:28:00 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
09-04 19:28:00 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
09-04 19:31:46 DEBUG [backup.model.syncer] Sync requested by Coordinator
09-04 19:31:46 INFO [backup.model.coordinator] Syncing Backups
09-04 19:31:46 INFO [backup.drive.drivesource] Uploading 'Full Backup 2023-09-04 05:18:12' to Google Drive
09-04 19:31:46 DEBUG [backup.drive.driverequests] Attempting to resume a previously failed upload where we left off
09-04 19:31:46 DEBUG [backup.drive.driverequests] Resuming upload at byte 271581184 of 861399040
09-04 19:31:46 DEBUG [backup.drive.driverequests] Sending 262144 bytes to Google Drive
09-04 19:31:47 DEBUG [backup.drive.drivesource] Uploading Full Backup 2023-09-04 05:18:12 31.56%
09-04 19:31:47 DEBUG [backup.drive.driverequests] Sending 7864320 bytes to Google Drive
09-04 19:32:09 ERROR [backup.model.coordinator] Google gave an unexpected response
09-04 19:32:09 INFO [backup.model.coordinator] I'll try again in 2 hours
spartan117aut commented 1 year ago

Wow, yesterdays update has been uploades successfully again. Error message still showing in the addon.

spartan117aut commented 2 months ago

I noticed a few months ago that daily backups were uploaded successfully again. I don't know if any addon update resolved a bug or if the problem was on the server side (Google), but the upload works consistently right now. @ffm777, can you confirm?