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

Backups failing for past 2 weeks different errors to other reports #980

Open thorrrr opened 5 months ago

thorrrr commented 5 months ago

I am getting daily backup frreezes in HA with addon. I have not changed anything for years so i guess its a change i HA or something

I have tried re setting credentials and resetting back to default also tried restarting several times.

01-27 13:00:24 INFO [backup.ui.uiserver] Starting server on port 8099 01-27 13:00:24 INFO [backup.ui.uiserver] Server started 01-27 13:00:24 DEBUG [backup.model.syncer] Sync requested by Coordinator 01-27 13:00:24 INFO [backup.model.coordinator] Syncing Backups 01-27 13:00:24 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials 01-27 13:01:04 INFO [backup.drive.drivesource] Saving new Google Drive credentials 01-27 13:01:04 DEBUG [backup.model.syncer] Sync requested by GoogleDrive 01-27 13:01:04 INFO [backup.model.coordinator] Syncing Backups 01-27 13:01:36 INFO [backup.ha.hasource] Requesting a new backup 01-27 13:01:36 WARNING [backup.ha.hasource] A backup was already in progress 01-27 13:01:36 ERROR [backup.ui.uiserver] Error serving POST http://192.168.23.137:8123/backup?custom_name=test&retain_drive=false&retain_ha=false¬e= 01-27 13:01:36 ERROR [backup.ui.uiserver]
addon/backup/ui/uiserver.py:801 (error_middleware)
addon/backup/ui/uiserver.py:298 (backup)
addon/backup/model/coordinator.py:253 (startBackup)
addon/backup/model/coordinator.py:353 (_withSoftLock)
addon/backup/model/coordinator.py:261 (_startBackup)
addon/backup/ha/hasource.py:222 (create)
addon/backup/ha/hasource.py:101 (raiseIfNeeded)
backup.exceptions.exceptions.BackupInProgress

01-27 13:01:36 DEBUG [backup.model.syncer] Sync requested by HomeAssistant 01-27 13:01:36 INFO [backup.model.coordinator] Syncing Backups

ppreget commented 5 months ago

👍 I have exactly the same. Managed to fixed it by stoping/starting the add-on

sabeechen commented 5 months ago

Then most common cause of this is an error while trying to start a backup in the supervisor, and the most common cause of that is a broken network share (if you have HA configured to use a network share).

When the addon has this error, you can usually click the "More Info" link on the pending backup to see the sueprvisor logs and determine what the root cause was. If that doesn't work, you can just check the supervisor logs manually.

thorrrr commented 5 months ago

HI Steve

THe share on Unraid i have been told has no issues and full read write permissions. I use many shares for many years for backups and media across my network. I have had 2 dev looks at my share from Unraid it has no issues The Unraid guys says it looks setup correctly it looks like the addon has issues knowing what is in the network share it just keeps backing up in a loop and does not know what is happening or able to count. It also starts at 2pm but i only have ever had an 11am sync time. 

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.1.6 Supervisor Version: 2023.12.1 Supervisor Channel: stable Hassos Version: 11.4 Docker Version: 24.0.7 Architecture: amd64 Machine: qemux86-64 Date: 2024-02-01T16:09:29.595729+00:00 Timezone: Europe/London Failure Time: Never Last Good Sync: 2024-02-01T14:21:52.572950+00:00 Next Sync: 2024-02-01T17:02:29.283449+00:00 Next Backup: 2024-02-02T11:00:00+00:00 Next Cache Warm: 2024-02-01T17:02:21.165047+00:00 Time Offset: 0.0

Exception:
 No error could be identified automatically.

Backups:

 {
    "HomeAssistant": {
        "backups": 1,
        "retained": 0,
        "deletable": 1,
        "name": "HomeAssistant",
        "title": "Home Assistant",
        "latest": "2024-02-01T14:00:00Z",
        "max": 4,
        "enabled": true,
        "icon": "home-assistant",
        "ignored": 0,
        "detail": "",
        "size": "0.0 B",
        "ignored_size": "0.0 B"
    },
    "GoogleDrive": {
        "backups": 4,
        "retained": 0,
        "deletable": 4,
        "name": "GoogleDrive",
        "title": "Google Drive",
        "latest": "2024-01-31T16:27:09Z",
        "max": 4,
        "enabled": true,
        "icon": "google-drive",
        "ignored": 0,
        "detail": "dalestorage1@gmail.com",
        "size": "10.8 GB",
        "ignored_size": "0.0 B",
        "free_space": "1.4 GB"
    }
}
Config:
 {
    "Setting.DAYS_BETWEEN_BACKUPS": 1.0,
    "Setting.BACKUP_TIME_OF_DAY": "11:00",
    "Setting.BACKUP_PASSWORD": "REDACTED",
    "Setting.BACKUP_STORAGE": "backups",
    "Setting.DELETE_AFTER_UPLOAD": true,
    "Setting.SEND_ERROR_REPORTS": true
}
Addon Logs:
 02-01 03:49:57 INFO [backup.model.coordinator] Syncing Backups
02-01 06:40:16 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
02-01 06:40:16 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
02-01 06:49:03 DEBUG [backup.model.syncer] Sync requested by Coordinator
02-01 06:49:03 INFO [backup.model.coordinator] Syncing Backups
02-01 08:29:17 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
02-01 08:29:17 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
02-01 08:43:47 DEBUG [backup.model.syncer] Sync requested by Coordinator
02-01 08:43:47 INFO [backup.model.coordinator] Syncing Backups
02-01 10:43:19 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
02-01 10:43:19 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
02-01 10:51:31 DEBUG [backup.model.syncer] Sync requested by Coordinator
02-01 10:51:31 INFO [backup.model.coordinator] Syncing Backups
02-01 10:52:20 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
02-01 11:00:00 DEBUG [backup.model.syncer] Sync requested by Coordinator
02-01 11:00:00 INFO [backup.model.coordinator] Syncing Backups
02-01 11:00:00 INFO [backup.ha.hasource] Requesting a new backup
02-01 11:22:17 WARNING [backup.ha.hasource] A backup was already in progress
02-01 11:22:18 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
02-01 11:22:18 INFO [backup.model.coordinator] Syncing Backups
02-01 13:34:21 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
02-01 13:34:21 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
02-01 13:46:31 DEBUG [backup.model.syncer] Sync requested by Coordinator
02-01 13:46:31 INFO [backup.model.coordinator] Syncing Backups
02-01 14:00:00 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
02-01 14:00:00 INFO [backup.model.coordinator] Syncing Backups
02-01 14:00:00 INFO [backup.ha.hasource] Requesting a new backup
02-01 14:21:52 WARNING [backup.ha.hasource] A backup was already in progress
02-01 14:21:52 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
02-01 14:21:52 INFO [backup.model.coordinator] Syncing Backups
Supervisor Logs:
 24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.check] System checks complete
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running
24-02-01 15:12:45 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
24-02-01 15:13:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:16:36 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
24-02-01 15:18:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:23:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:28:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:33:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:38:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:43:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:46:37 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
24-02-01 15:48:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:53:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 15:58:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 16:03:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 16:08:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 16:09:29 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
Home Assistant Core Logs:
 2024-02-01 16:00:10.241 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T15:55:32.929654+00:00 (old last_reset 2024-02-01T15:54:31.919237+00:00)
2024-02-01 16:00:10.242 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T15:56:33.855034+00:00 (old last_reset 2024-02-01T15:55:32.929654+00:00)
2024-02-01 16:00:10.242 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T15:57:34.899395+00:00 (old last_reset 2024-02-01T15:56:33.855034+00:00)
2024-02-01 16:00:10.242 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T15:58:35.966255+00:00 (old last_reset 2024-02-01T15:57:34.899395+00:00)
2024-02-01 16:00:10.242 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T15:59:36.914495+00:00 (old last_reset 2024-02-01T15:58:35.966255+00:00)
2024-02-01 16:00:10.242 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_gas_g4p56045261700_1671692907_current_consumption, last_reset set to 2024-02-01T15:55:04.373859+00:00 (old last_reset 2024-02-01T15:53:02.013582+00:00)
2024-02-01 16:00:10.242 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_gas_g4p56045261700_1671692907_current_consumption, last_reset set to 2024-02-01T15:57:04.883516+00:00 (old last_reset 2024-02-01T15:55:04.373859+00:00)
2024-02-01 16:00:10.242 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_gas_g4p56045261700_1671692907_current_consumption, last_reset set to 2024-02-01T15:59:05.967641+00:00 (old last_reset 2024-02-01T15:57:04.883516+00:00)
2024-02-01 16:01:56.206 INFO (MainThread) [alexapy.alexaapi] Backing off _static_request(...) for 0.2s (alexapy.errors.AlexapyTooManyRequestsError: Too Many Requests)
2024-02-01 16:02:08.797 INFO (MainThread) [homeassistant.components.automation.galley_lights_on_off] KITCHEN - Galley Lights On-Off: Running automation actions
2024-02-01 16:02:08.797 INFO (MainThread) [homeassistant.components.automation.galley_lights_on_off] KITCHEN - Galley Lights On-Off: If at step 1: Running automation actions
2024-02-01 16:02:08.797 INFO (MainThread) [homeassistant.components.automation.galley_lights_on_off] KITCHEN - Galley Lights On-Off: If at step 1: Executing step call service
2024-02-01 16:04:14.057 INFO (MainThread) [homeassistant.components.automation.dish_washer_started] KITCHEN Dish Washer Started: Running automation actions
2024-02-01 16:04:14.057 INFO (MainThread) [homeassistant.components.automation.dish_washer_started] KITCHEN Dish Washer Started: Executing step call service
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption_cost, last_reset set to 2024-02-01T16:00:38.069418+00:00 (old last_reset 2024-02-01T15:59:36.915707+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption_cost, last_reset set to 2024-02-01T16:01:38.897043+00:00 (old last_reset 2024-02-01T16:00:38.069418+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption_cost, last_reset set to 2024-02-01T16:02:39.902244+00:00 (old last_reset 2024-02-01T16:01:38.897043+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption_cost, last_reset set to 2024-02-01T16:03:40.940969+00:00 (old last_reset 2024-02-01T16:02:39.902244+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption_cost, last_reset set to 2024-02-01T16:04:41.983125+00:00 (old last_reset 2024-02-01T16:03:40.940969+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_gas_g4p56045261700_1671692907_current_consumption_cost, last_reset set to 2024-02-01T16:01:06.903328+00:00 (old last_reset 2024-02-01T15:59:05.969650+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_gas_g4p56045261700_1671692907_current_consumption_cost, last_reset set to 2024-02-01T16:03:07.817160+00:00 (old last_reset 2024-02-01T16:01:06.903328+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T16:00:38.068341+00:00 (old last_reset 2024-02-01T15:59:36.914495+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T16:01:38.891916+00:00 (old last_reset 2024-02-01T16:00:38.068341+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T16:02:39.901155+00:00 (old last_reset 2024-02-01T16:01:38.891916+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T16:03:40.939809+00:00 (old last_reset 2024-02-01T16:02:39.901155+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_electricity_17p5604074_1300004118991_current_consumption, last_reset set to 2024-02-01T16:04:41.982009+00:00 (old last_reset 2024-02-01T16:03:40.939809+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_gas_g4p56045261700_1671692907_current_consumption, last_reset set to 2024-02-01T16:01:06.902145+00:00 (old last_reset 2024-02-01T15:59:05.967641+00:00)
2024-02-01 16:05:10.251 INFO (Recorder) [homeassistant.components.sensor.recorder] Detected new cycle for sensor.octopus_energy_gas_g4p56045261700_1671692907_current_consumption, last_reset set to 2024-02-01T16:03:07.815702+00:00 (old last_reset 2024-02-01T16:01:06.902145+00:00)
2024-02-01 16:07:12.157 INFO (Thread-16 (_run)) [google.api_core.bidi] Re-established stream

home-assistant-google-drive-backup.log supervisor_2024-02-01T16-11-51.869Z.log

thorrrr commented 5 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.1.6 Supervisor Version: 2023.12.1 Supervisor Channel: stable Hassos Version: 11.4 Docker Version: 24.0.7 Architecture: amd64 Machine: qemux86-64 Date: 2024-02-01T19:49:24.438682+00:00 Timezone: Europe/London Failure Time: 2024-02-01T19:34:02.825683+00:00 Last Good Sync: 2024-02-01T19:34:02.296529+00:00 Next Sync: 2024-02-01T21:34:02.825683+00:00 Next Backup: 2024-02-02T11:00:00+00:00 Next Cache Warm: 2024-02-01T21:19:29.719210+00:00 Time Offset: 0.0

Exception:

  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:250 (sync)
  addon/backup/drive/drivesource.py:197 (save)
  addon/backup/util/asynchttpgetter.py:228 (__aenter__)
  addon/backup/util/asynchttpgetter.py:61 (setup)
  addon/backup/util/asynchttpgetter.py:167 (_startReadRemoteAt)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:601 (_request)
  /site-packages/aiohttp/client_reqrep.py:965 (start)
  /site-packages/aiohttp/streams.py:622 (read)
aiohttp.client_exceptions.ServerDisconnectedError: Server disconnected

Backups:

 {
    "HomeAssistant": {
        "backups": 1,
        "retained": 0,
        "deletable": 1,
        "name": "HomeAssistant",
        "title": "Home Assistant",
        "latest": "2024-02-01T17:00:02Z",
        "max": 4,
        "enabled": true,
        "icon": "home-assistant",
        "ignored": 0,
        "detail": "",
        "size": "0.0 B",
        "ignored_size": "0.0 B"
    },
    "GoogleDrive": {
        "backups": 1,
        "retained": 0,
        "deletable": 1,
        "name": "GoogleDrive",
        "title": "Google Drive",
        "latest": "2024-01-31T16:27:09Z",
        "max": 4,
        "enabled": true,
        "icon": "google-drive",
        "ignored": 0,
        "detail": "dalestorage1@gmail.com",
        "size": "2.7 GB",
        "ignored_size": "0.0 B",
        "free_space": "12.2 GB"
    }
}
Config:
 {
    "Setting.DAYS_BETWEEN_BACKUPS": 1.0,
    "Setting.BACKUP_TIME_OF_DAY": "11:00",
    "Setting.BACKUP_PASSWORD": "REDACTED",
    "Setting.BACKUP_STORAGE": "backups",
    "Setting.DELETE_AFTER_UPLOAD": true,
    "Setting.SEND_ERROR_REPORTS": true
}
Addon Logs:
 02-01 18:55:53 DEBUG [backup.drive.drivesource] Uploading Full Backup 2024-02-01 17:00:01 99.04%
02-01 18:55:53 DEBUG [backup.drive.driverequests] Sending 10 MB to Google Drive
02-01 18:55:58 DEBUG [backup.drive.drivesource] Uploading Full Backup 2024-02-01 17:00:01 99.40%
02-01 18:55:58 DEBUG [backup.drive.driverequests] Sending 10 MB to Google Drive
02-01 18:56:03 DEBUG [backup.drive.drivesource] Uploading Full Backup 2024-02-01 17:00:01 99.75%
02-01 18:56:03 DEBUG [backup.drive.driverequests] Sending 6.875 MB to Google Drive
02-01 18:56:08 DEBUG [backup.drive.drivesource] Uploading Full Backup 2024-02-01 17:00:01 100.00%
02-01 18:56:09 INFO [backup.ha.hasource] Deleting 'Full Backup 2024-02-01 17:00:01' from Home Assistant
02-01 18:56:09 ERROR [backup.model.coordinator] Home Assistant refused to delete the backup.
02-01 18:56:09 INFO [backup.model.coordinator] I'll try again in 2 hours
02-01 18:56:10 INFO [backup.debugworker] Sending error report (see settings to disable)
02-01 19:33:20 INFO [backup.model.coordinator] Syncing Backups
02-01 19:33:20 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
02-01 19:33:21 INFO [backup.ha.hasource] Deleting 'Full Backup 2024-02-01 17:00:01' from Home Assistant
02-01 19:33:21 ERROR [backup.model.coordinator] Home Assistant refused to delete the backup.
02-01 19:33:21 INFO [backup.model.coordinator] I'll try again in 2 hours
02-01 19:33:43 INFO [backup.ha.hasource] Deleting 'Full Backup 2024-02-01 17:00:01' from Home Assistant
02-01 19:33:43 ERROR [backup.ui.uiserver] Error serving POST http://192.168.23.137:8123/deleteSnapshot
02-01 19:33:43 ERROR [backup.ui.uiserver] 
  addon/backup/ha/harequests.py:105 (delete)
  addon/backup/ha/harequests.py:34 (wrap_and_call)
  addon/backup/ha/harequests.py:280 (_sendHassioData)
  addon/backup/ha/harequests.py:225 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1059 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 400, message='Bad Request', url=URL('http://supervisor/backups/d87f741f')
Whose handling caused:
  addon/backup/ui/uiserver.py:801 (error_middleware)
  addon/backup/ui/uiserver.py:305 (deleteSnapshot)
  addon/backup/model/coordinator.py:295 (delete)
  addon/backup/model/coordinator.py:353 (_withSoftLock)
  addon/backup/model/coordinator.py:304 (_delete)
  addon/backup/ha/hasource.py:355 (delete)
  addon/backup/ha/harequests.py:34 (wrap_and_call)
  addon/backup/ha/harequests.py:111 (delete)
backup.exceptions.exceptions.HomeAssistantDeleteError

02-01 19:33:52 INFO [backup.drive.drivesource] Deleting 'Full Backup 2024-02-01 17:00:01' From Google Drive
02-01 19:33:56 INFO [backup.ha.hasource] Deleting 'Full Backup 2024-02-01 17:00:01' from Home Assistant
02-01 19:33:56 ERROR [backup.ui.uiserver] Error serving POST http://192.168.23.137:8123/deleteSnapshot
02-01 19:33:56 ERROR [backup.ui.uiserver] 
  addon/backup/ha/harequests.py:105 (delete)
  addon/backup/ha/harequests.py:34 (wrap_and_call)
  addon/backup/ha/harequests.py:280 (_sendHassioData)
  addon/backup/ha/harequests.py:225 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1059 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 400, message='Bad Request', url=URL('http://supervisor/backups/d87f741f')
Whose handling caused:
  addon/backup/ui/uiserver.py:801 (error_middleware)
  addon/backup/ui/uiserver.py:305 (deleteSnapshot)
  addon/backup/model/coordinator.py:295 (delete)
  addon/backup/model/coordinator.py:353 (_withSoftLock)
  addon/backup/model/coordinator.py:304 (_delete)
  addon/backup/ha/hasource.py:355 (delete)
  addon/backup/ha/harequests.py:34 (wrap_and_call)
  addon/backup/ha/harequests.py:111 (delete)
backup.exceptions.exceptions.HomeAssistantDeleteError

02-01 19:34:02 INFO [backup.model.coordinator] Syncing Backups
02-01 19:34:02 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:250 (sync)
  addon/backup/drive/drivesource.py:197 (save)
  addon/backup/util/asynchttpgetter.py:228 (__aenter__)
  addon/backup/util/asynchttpgetter.py:61 (setup)
  addon/backup/util/asynchttpgetter.py:167 (_startReadRemoteAt)
  addon/backup/tracing_session.py:39 (_request)
  /site-packages/aiohttp/client.py:601 (_request)
  /site-packages/aiohttp/client_reqrep.py:965 (start)
  /site-packages/aiohttp/streams.py:622 (read)
aiohttp.client_exceptions.ServerDisconnectedError: Server disconnected

02-01 19:34:02 INFO [backup.model.coordinator] I'll try again in 2 hours
02-01 19:34:11 INFO [backup.debugworker] Sending error report (see settings to disable)
02-01 19:34:28 INFO [backup.ha.hasource] Deleting 'Full Backup 2024-02-01 17:00:01' from Home Assistant
02-01 19:34:28 ERROR [backup.ui.uiserver] Error serving POST http://192.168.23.137:8123/deleteSnapshot
02-01 19:34:28 ERROR [backup.ui.uiserver] 
  addon/backup/ha/harequests.py:105 (delete)
  addon/backup/ha/harequests.py:34 (wrap_and_call)
  addon/backup/ha/harequests.py:280 (_sendHassioData)
  addon/backup/ha/harequests.py:225 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1059 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 400, message='Bad Request', url=URL('http://supervisor/backups/d87f741f')
Whose handling caused:
  addon/backup/ui/uiserver.py:801 (error_middleware)
  addon/backup/ui/uiserver.py:305 (deleteSnapshot)
  addon/backup/model/coordinator.py:295 (delete)
  addon/backup/model/coordinator.py:353 (_withSoftLock)
  addon/backup/model/coordinator.py:304 (_delete)
  addon/backup/ha/hasource.py:355 (delete)
  addon/backup/ha/harequests.py:34 (wrap_and_call)
  addon/backup/ha/harequests.py:111 (delete)
backup.exceptions.exceptions.HomeAssistantDeleteError
Supervisor Logs:
   File "/usr/local/lib/python3.11/pathlib.py", line 1013, in stat
    return os.stat(self, follow_symlinks=follow_symlinks)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
FileNotFoundError: [Errno 2] No such file or directory: '/data/mounts/backups/d87f741f.tar'
24-02-01 19:34:28 INFO (MainThread) [supervisor.api.middleware.security] /backups/d87f741f access from cebe7a76_hassio_google_drive_backup
24-02-01 19:34:28 ERROR (MainThread) [supervisor.backups.manager] Can't remove backup d87f741f: [Errno 2] No such file or directory: '/data/mounts/backups/d87f741f.tar'
24-02-01 19:38:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 19:43:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 19:46:52 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
24-02-01 19:47:19 INFO (SyncWorker_5) [supervisor.docker.manager] Restarting homeassistant
24-02-01 19:47:19 WARNING (MainThread) [supervisor.homeassistant.websocket] Connection is closed
24-02-01 19:47:45 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
24-02-01 19:47:48 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
24-02-01 19:47:48 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-02-01 19:47:49 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/zigbee2mqtt/hassio-zigbee2mqtt repository
24-02-01 19:47:49 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
24-02-01 19:47:49 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/sabeechen/hassio-google-drive-backup repository
24-02-01 19:47:49 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
24-02-01 19:47:49 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
24-02-01 19:47:49 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
24-02-01 19:47:50 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to NOT_RUNNING
24-02-01 19:47:52 INFO (MainThread) [supervisor.store] Loading add-ons from store: 81 all - 0 new - 0 remove
24-02-01 19:47:52 INFO (MainThread) [supervisor.store] Loading add-ons from store: 81 all - 0 new - 0 remove
24-02-01 19:47:52 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
24-02-01 19:47:56 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'mqtt-user'
24-02-01 19:47:57 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
24-02-01 19:48:26 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to RUNNING
24-02-01 19:48:26 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance
24-02-01 19:49:24 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
Home Assistant Core Logs:
 Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/pyhik/hikvision.py", line 514, in alert_stream
    raise ValueError('Connection unsucessful.')
ValueError: Connection unsucessful.

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/local/lib/python3.11/threading.py", line 1045, in _bootstrap_inner
    self.run()
  File "/usr/local/lib/python3.11/threading.py", line 982, in run
    self._target(*self._args, **self._kwargs)
  File "/usr/local/lib/python3.11/site-packages/pyhik/hikvision.py", line 575, in alert_stream
    self.update_stale()
  File "/usr/local/lib/python3.11/site-packages/pyhik/hikvision.py", line 637, in update_stale
    for etype, echannels in self.event_states.items():
                            ^^^^^^^^^^^^^^^^^^^^^^^
AttributeError: 'NoneType' object has no attribute 'items'
2024-02-01 19:48:28.855 INFO (MainThread) [homeassistant.components.automation.galley_lights_on_off] KITCHEN - Galley Lights On-Off: Running automation actions
2024-02-01 19:48:28.856 INFO (MainThread) [homeassistant.components.automation.galley_lights_on_off] KITCHEN - Galley Lights On-Off: If at step 1: Running automation actions
2024-02-01 19:48:28.856 INFO (MainThread) [homeassistant.components.automation.galley_lights_on_off] KITCHEN - Galley Lights On-Off: If at step 1: Executing step call service
2024-02-01 19:48:58.457 INFO (MainThread) [custom_components.hacs] Starting update, v4.1.2
2024-02-01 19:48:58.458 INFO (MainThread) [custom_components.hacs] <Plugin custom-cards/button-card> Running pre installation steps
2024-02-01 19:48:58.458 INFO (MainThread) [custom_components.hacs] <Plugin custom-cards/button-card> Pre installation steps completed
2024-02-01 19:48:58.458 INFO (MainThread) [custom_components.hacs] <Plugin custom-cards/button-card> Running installation steps
2024-02-01 19:49:00.399 INFO (MainThread) [custom_components.hacs] <Plugin custom-cards/button-card> Download of button-card.js completed
2024-02-01 19:49:01.400 INFO (MainThread) [custom_components.hacs] <Plugin custom-cards/button-card> Installation steps completed
2024-02-01 19:49:01.400 INFO (MainThread) [custom_components.hacs] <Plugin custom-cards/button-card> Running post installation steps
2024-02-01 19:49:01.400 INFO (MainThread) [custom_components.hacs] <Plugin custom-cards/button-card> Post installation steps completed
sabeechen commented 4 months ago

The error in your supervisor logs:

[31m24-02-01 19:34:28 ERROR (MainThread) [supervisor.backups.manager] Can't remove backup d87f741f: [Errno 2] No such file or directory: '/data/mounts/backups/d87f741f.tar'

Indicates Home Assistant is having some kind of problem with your network share. It could be file permissions, it could be that files were deleted from the share without Home Assistant being aware, or a number of other things. Home Assistant doesn't provide a lot of help in its logs or otherwise when there is a problem with network shares. The error HA reports to this addon when there are problems looks identical to the "Backup in Progress" error, so the addon can't tell the difference.

If you're sure your network share is working fine, I'd recommend:

Hopefully HA is just stuck thinking an old non-existent backup should be present.