search

sabeechen / hassio-google-drive-backup

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

Issue with 2023.6 #863

Closed haaino closed 1 year ago

haaino 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.110.4 Home Assistant Version: 2023.6.1 Supervisor Version: 2023.06.1 Supervisor Channel: stable Hassos Version: 10.2 Docker Version: 23.0.6 Architecture: aarch64 Machine: odroid-n2 Date: 2023-06-12T20:54:41.898092+00:00 Timezone: Europe/Amsterdam Failure Time: 2023-06-12T20:53:51.164079+00:00 Last Good Sync: 2023-06-12T20:53:41.048327+00:00 Next Sync: 2023-06-12T22:53:51.164079+00:00 Next Backup: 2023-06-15T20:53:30.797275+00:00 Next Cache Warm: 2023-06-12T22:43:52.090980+00:00 Time Offset: 0.0

Exception:

  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:199 (sync)
  addon/backup/model/model.py:312 (_syncBackups)
  addon/backup/ha/hasource.py:260 (get)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:130 (backups)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:252 (_getHassioData)
  addon/backup/ha/harequests.py:208 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1005 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 500, message='Internal Server Error', url=URL('http://supervisor/backups')

Backups:

 {
    "HomeAssistant": {
        "backups": 5,
        "retained": 0,
        "deletable": 5,
        "name": "HomeAssistant",
        "title": "Home Assistant",
        "latest": "2023-06-12T20:53:30Z",
        "max": 4,
        "enabled": true,
        "icon": "home-assistant",
        "ignored": 0,
        "detail": "",
        "size": "4.2 GB",
        "ignored_size": "0.0 B",
        "free_space": "84.1 GB"
    },
    "GoogleDrive": {
        "backups": 9,
        "retained": 0,
        "deletable": 9,
        "name": "GoogleDrive",
        "title": "Google Drive",
        "latest": "2023-06-11T07:24:20Z",
        "max": 10,
        "enabled": true,
        "icon": "google-drive",
        "ignored": 0,
        "detail": "haaino@gmail.com",
        "size": "8.4 GB",
        "ignored_size": "0.0 B",
        "free_space": "6.4 GB"
    }
}
Config:
 {
    "Setting.MAX_BACKUPS_IN_GOOGLE_DRIVE": 10,
    "Setting.IGNORE_UPGRADE_BACKUPS": false
}
Addon Logs:
 06-12 21:00:59 INFO [backup.model.coordinator] I'll try again in 5 minutes, 20 seconds
06-12 21:06:19 DEBUG [backup.model.syncer] Sync requested by Coordinator
06-12 21:06:19 INFO [backup.model.coordinator] Syncing Backups
06-12 21:06:29 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:199 (sync)
  addon/backup/model/model.py:312 (_syncBackups)
  addon/backup/ha/hasource.py:260 (get)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:130 (backups)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:252 (_getHassioData)
  addon/backup/ha/harequests.py:208 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1005 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 500, message='Internal Server Error', url=URL('http://supervisor/backups')

06-12 21:06:29 INFO [backup.model.coordinator] I'll try again in 10 minutes, 40 seconds
06-12 21:08:42 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
06-12 21:17:09 DEBUG [backup.model.syncer] Sync requested by Coordinator
06-12 21:17:09 INFO [backup.model.coordinator] Syncing Backups
06-12 21:17:19 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:199 (sync)
  addon/backup/model/model.py:312 (_syncBackups)
  addon/backup/ha/hasource.py:260 (get)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:130 (backups)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:252 (_getHassioData)
  addon/backup/ha/harequests.py:208 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1005 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 500, message='Internal Server Error', url=URL('http://supervisor/backups')

06-12 21:17:19 INFO [backup.model.coordinator] I'll try again in 21 minutes, 20 seconds
06-12 21:35:43 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
06-12 21:38:40 DEBUG [backup.model.syncer] Sync requested by Coordinator
06-12 21:38:40 INFO [backup.model.coordinator] Syncing Backups
06-12 21:38:50 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:199 (sync)
  addon/backup/model/model.py:312 (_syncBackups)
  addon/backup/ha/hasource.py:260 (get)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:130 (backups)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:252 (_getHassioData)
  addon/backup/ha/harequests.py:208 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1005 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 500, message='Internal Server Error', url=URL('http://supervisor/backups')

06-12 21:38:50 INFO [backup.model.coordinator] I'll try again in 42 minutes, 40 seconds
06-12 22:11:44 DEBUG [backup.model.destinationprecache] Preemptively retrieving and caching info from the backup destination to avoid peak demand
06-12 22:11:44 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
06-12 22:21:30 DEBUG [backup.model.syncer] Sync requested by Coordinator
06-12 22:21:30 INFO [backup.model.coordinator] Syncing Backups
06-12 22:21:40 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:199 (sync)
  addon/backup/model/model.py:312 (_syncBackups)
  addon/backup/ha/hasource.py:260 (get)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:130 (backups)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:252 (_getHassioData)
  addon/backup/ha/harequests.py:208 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1005 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 500, message='Internal Server Error', url=URL('http://supervisor/backups')

06-12 22:21:40 INFO [backup.model.coordinator] I'll try again in 1 hours, 25 minutes, 20 seconds
06-12 22:51:58 INFO [backup.model.coordinator] Syncing Backups
06-12 22:52:08 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:199 (sync)
  addon/backup/model/model.py:312 (_syncBackups)
  addon/backup/ha/hasource.py:260 (get)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:130 (backups)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:252 (_getHassioData)
  addon/backup/ha/harequests.py:208 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1005 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 500, message='Internal Server Error', url=URL('http://supervisor/backups')

06-12 22:52:08 INFO [backup.model.coordinator] I'll try again in 2 hours
06-12 22:53:30 INFO [backup.ha.hasource] Requesting a new backup
06-12 22:53:40 WARNING [backup.ha.hasource] A backup was already in progress
06-12 22:53:41 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
06-12 22:53:41 INFO [backup.model.coordinator] Syncing Backups
06-12 22:53:51 ERROR [backup.model.coordinator] 
  addon/backup/model/coordinator.py:198 (_sync)
  addon/backup/model/model.py:199 (sync)
  addon/backup/model/model.py:312 (_syncBackups)
  addon/backup/ha/hasource.py:260 (get)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:130 (backups)
  addon/backup/ha/harequests.py:33 (wrap_and_call)
  addon/backup/ha/harequests.py:252 (_getHassioData)
  addon/backup/ha/harequests.py:208 (_validateHassioReply)
  /site-packages/aiohttp/client_reqrep.py:1005 (raise_for_status)
aiohttp.client_exceptions.ClientResponseError: 500, message='Internal Server Error', url=URL('http://supervisor/backups')

06-12 22:53:51 INFO [backup.model.coordinator] I'll try again in 2 hours
Supervisor Logs:
   File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 268, in token_validation
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/middleware/security.py", line 280, in core_proxy
    return await handler(request)
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/utils.py", line 62, in wrap_api
    answer = await method(api, *args, **kwargs)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/backups.py", line 117, in list
    data_backups = self._list_backups()
                   ^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/api/backups.py", line 96, in _list_backups
    return [
           ^
  File "/usr/src/supervisor/supervisor/api/backups.py", line 102, in <listcomp>
    ATTR_SIZE: backup.size,
               ^^^^^^^^^^^
  File "/usr/src/supervisor/supervisor/backups/backup.py", line 156, in size
    if not self.tarfile.is_file():
           ^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/pathlib.py", line 1267, in is_file
    return S_ISREG(self.stat().st_mode)
                   ^^^^^^^^^^^
  File "/usr/local/lib/python3.11/pathlib.py", line 1013, in stat
    return os.stat(self, follow_symlinks=follow_symlinks)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
OSError: [Errno 112] Host is down: '/data/mounts/Backup_synolgy/5caebc25.tar'
23-06-12 22:54:41 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
Home Assistant Core Logs:
 2023-06-12 22:52:30.141 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Executing step call service
2023-06-12 22:52:33.536 INFO (MainThread) [homeassistant.components.websocket_api.http.connection] [281471486282512] Connection closed by client
2023-06-12 22:52:33.537 INFO (MainThread) [homeassistant.components.websocket_api.http.connection] [281471889225744] Connection closed by client
2023-06-12 22:52:40.378 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Running automation actions
2023-06-12 22:52:40.378 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Executing step call service
2023-06-12 22:53:00.068 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Running automation actions
2023-06-12 22:53:00.068 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Executing step call service
2023-06-12 22:53:00.299 INFO (MainThread) [homeassistant.components.automation.keuken_warmwater_boiler_aan_en_uit] Keuken: warmwater boiler aan en uit: Running automation actions
2023-06-12 22:53:00.300 INFO (MainThread) [homeassistant.components.automation.keuken_warmwater_boiler_aan_en_uit] Keuken: warmwater boiler aan en uit: If at step 2: Running automation actions
2023-06-12 22:53:00.300 INFO (MainThread) [homeassistant.components.automation.keuken_warmwater_boiler_aan_en_uit] Keuken: warmwater boiler aan en uit: If at step 2: Executing step call service
2023-06-12 22:53:10.322 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Running automation actions
2023-06-12 22:53:10.322 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Executing step call service
2023-06-12 22:53:20.382 INFO (MainThread) [homeassistant.components.websocket_api.http.connection] [281471889892048] Connection closed by client
2023-06-12 22:53:20.385 INFO (MainThread) [homeassistant.components.websocket_api.http.connection] [281471519766608] Connection closed by client
2023-06-12 22:53:30.141 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Running automation actions
2023-06-12 22:53:30.141 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Executing step call service
2023-06-12 22:53:40.377 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Running automation actions
2023-06-12 22:53:40.378 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Executing step call service
2023-06-12 22:54:00.070 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Running automation actions
2023-06-12 22:54:00.070 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Executing step call service
2023-06-12 22:54:00.301 INFO (MainThread) [homeassistant.components.automation.keuken_warmwater_boiler_aan_en_uit] Keuken: warmwater boiler aan en uit: Running automation actions
2023-06-12 22:54:00.303 INFO (MainThread) [homeassistant.components.automation.keuken_warmwater_boiler_aan_en_uit] Keuken: warmwater boiler aan en uit: If at step 2: Running automation actions
2023-06-12 22:54:00.303 INFO (MainThread) [homeassistant.components.automation.keuken_warmwater_boiler_aan_en_uit] Keuken: warmwater boiler aan en uit: If at step 2: Executing step call service
2023-06-12 22:54:10.323 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Running automation actions
2023-06-12 22:54:10.323 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Executing step call service
2023-06-12 22:54:30.139 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Running automation actions
2023-06-12 22:54:30.140 INFO (MainThread) [homeassistant.components.automation.bijkeuken_oven_steeds_aan] Woonkamer: Dyson tussenstekker steeds aan: Executing step call service
2023-06-12 22:54:40.378 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Running automation actions
2023-06-12 22:54:40.379 INFO (MainThread) [homeassistant.components.automation.tussen_stekker_koelkast_bijkeuken] Kritieke tussenstekkers steeds aan doen: Executing step call service
sabeechen commented 1 year ago

The problem is right here:

OSError: [Errno 112] Host is down: '/data/mounts/Backup_synolgy/5caebc25.tar'

Home Assistant (the supervisor specifically) can't access your share.

haaino commented 1 year ago

Thx. You were right!

made a mistake somewhere