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

Will not complete a backup #1046

Closed tcochran1987 closed 1 month ago

tcochran1987 commented 1 month 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.5.4 Supervisor Version: 2024.05.1 Supervisor Channel: stable Hassos Version: 12.2 Docker Version: 25.0.5 Architecture: amd64 Machine: generic-x86-64 Date: 2024-05-23T11:15:19.069993+00:00 Timezone: America/New_York Failure Time: 2024-05-23T10:34:46.616594+00:00 Last Good Sync: 2024-05-23T10:34:47.119263+00:00 Next Sync: 2024-05-23T12:33:53.079542+00:00 Next Backup: 2024-05-26T07:30:00+00:00 Next Cache Warm: 2260-12-09T11:37:23.068424+00:00 Time Offset: 0.0

Exception:
 No error could be identified automatically.

Backups:

 {
    "HomeAssistant": {
        "backups": 3,
        "retained": 0,
        "deletable": 3,
        "name": "HomeAssistant",
        "title": "Home Assistant",
        "latest": "2024-05-23T10:34:45Z",
        "max": 8,
        "enabled": true,
        "icon": "home-assistant",
        "ignored": 3,
        "detail": "",
        "size": "1.4 GB",
        "ignored_size": "1.2 MB"
    },
    "GoogleDrive": {
        "backups": 2,
        "retained": 0,
        "deletable": 2,
        "name": "GoogleDrive",
        "title": "Google Drive",
        "latest": "2024-04-22T07:00:00Z",
        "max": 4,
        "enabled": true,
        "icon": "google-drive",
        "ignored": 0,
        "detail": "tcochran1987@gmail.com",
        "size": "1.4 GB",
        "ignored_size": "0.0 B",
        "free_space": "10.3 GB"
    }
}
Config:
 {
    "Setting.MAX_BACKUPS_IN_HA": 8,
    "Setting.BACKUP_TIME_OF_DAY": "03:30",
    "Setting.EXCLUDE_FOLDERS": "media,ssl,share",
    "Setting.EXCLUDE_ADDONS": "a0d7b954_influxdb,a0d7b954_grafana,core_rpc_shutdown,a0d7b954_nut,core_vlc,a0d7b954_uptime-kuma,5c53de3b_esphome,core_zwave_js,a0d7b954_vscode,a0d7b954_grocy,core_whisper,core_piper,core_letsencrypt,d5369777_music_assistant,cebe7a76_hassio_google_drive_backup",
    "Setting.EXCLUDE_HA_DATABASE": true,
    "Setting.SEND_ERROR_REPORTS": true,
    "Setting.IGNORE_IPV6_ADDRESSES": true,
    "Setting.ALTERNATE_DNS_SERVERS": "8.8.8.8,8.8.4.4,1.1.1.1"
}
Addon Logs:
 05-23 03:31:13 INFO [backup.model.coordinator] Syncing Backups
05-23 03:31:14 INFO [backup.ha.hasource] A backup was already in progress
05-23 03:31:14 ERROR [backup.model.coordinator] A backup is already in progress
05-23 03:31:14 INFO [backup.model.coordinator] I'll try again in 1 minutes, 20 seconds
05-23 03:32:34 DEBUG [backup.model.syncer] Sync requested by Coordinator
05-23 03:32:34 INFO [backup.model.coordinator] Syncing Backups
05-23 03:32:35 INFO [backup.ha.hasource] A backup was already in progress
05-23 03:32:35 ERROR [backup.model.coordinator] A backup is already in progress
05-23 03:32:35 INFO [backup.model.coordinator] I'll try again in 2 minutes, 40 seconds
05-23 03:34:44 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
05-23 03:34:44 INFO [backup.model.coordinator] Syncing Backups
05-23 03:34:45 INFO [backup.ha.hasource] Requesting a new backup
05-23 03:34:45 WARNING [backup.ha.hasource] A backup was already in progress
05-23 03:34:45 ERROR [backup.model.coordinator] A backup is already in progress
05-23 03:34:45 INFO [backup.model.coordinator] I'll try again in 5 minutes, 20 seconds
05-23 03:34:45 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
05-23 03:34:45 INFO [backup.model.coordinator] Syncing Backups
05-23 03:34:52 INFO [backup.debugworker] Sending error report (see settings to disable)
05-23 06:00:56 DEBUG [backup.model.syncer] Sync requested by Coordinator
05-23 06:00:56 INFO [backup.model.coordinator] Syncing Backups
05-23 06:00:56 DEBUG [backup.drive.driverequests] Requesting refreshed Google Drive credentials
05-23 06:34:45 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
05-23 06:34:45 INFO [backup.model.coordinator] Syncing Backups
05-23 06:34:46 INFO [backup.ha.hasource] Requesting a new backup
05-23 06:34:46 WARNING [backup.ha.hasource] A backup was already in progress
05-23 06:34:46 ERROR [backup.model.coordinator] A backup is already in progress
05-23 06:34:46 INFO [backup.model.coordinator] I'll try again in 0 seconds
05-23 06:34:47 DEBUG [backup.model.syncer] Sync requested by Coordinator
05-23 06:34:47 DEBUG [backup.model.syncer] Sync requested by HomeAssistant
05-23 06:34:47 INFO [backup.model.coordinator] Syncing Backups
Supervisor Logs:
     fileobj = bltn_open(name, self._mode)
              ^^^^^^^^^^^^^^^^^^^^^^^^^^^
PermissionError: [Errno 13] Permission denied: '/data/mounts/HABackup/1aa11c50.tar'
2024-05-23 06:34:46.111 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
2024-05-23 06:34:47.121 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
2024-05-23 06:34:47.125 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup
2024-05-23 06:51:51.648 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
2024-05-23 06:53:11.388 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
2024-05-23 06:56:11.883 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state running
2024-05-23 06:56:11.884 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system
2024-05-23 06:56:11.884 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server
2024-05-23 06:56:11.885 INFO (MainThread) [supervisor.resolution.checks.base] Run check for disabled_data_disk/system
2024-05-23 06:56:11.885 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system
2024-05-23 06:56:11.885 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system
2024-05-23 06:56:11.886 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon
2024-05-23 06:56:11.886 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system
2024-05-23 06:56:11.886 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server
2024-05-23 06:56:11.887 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor
2024-05-23 06:56:11.898 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core
2024-05-23 06:56:11.899 INFO (MainThread) [supervisor.resolution.check] System checks complete
2024-05-23 06:56:11.899 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
2024-05-23 06:56:12.181 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
2024-05-23 06:56:12.182 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running
2024-05-23 06:56:12.182 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
2024-05-23 07:15:00.699 INFO (MainThread) [supervisor.api.middleware.security] /core/info access from cebe7a76_hassio_google_drive_backup
2024-05-23 07:15:00.702 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup
2024-05-23 07:15:00.705 INFO (MainThread) [supervisor.api.middleware.security] /mounts access from cebe7a76_hassio_google_drive_backup
2024-05-23 07:15:00.707 INFO (MainThread) [supervisor.api.middleware.security] /addons access from cebe7a76_hassio_google_drive_backup
2024-05-23 07:15:19.088 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/logs access from cebe7a76_hassio_google_drive_backup
Home Assistant Core Logs:
 2024-05-22 22:16:01.257 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: [Errno 113] Connect call failed ('172.24.1.97', 9999)
2024-05-22 22:16:04.329 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: [Errno 113] Connect call failed ('172.24.1.97', 9999)
2024-05-22 22:17:00.031 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: 
2024-05-22 22:26:48.032 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: 
2024-05-22 22:40:54.058 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_113, Host is unreachable) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-22 22:50:48.034 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: 
2024-05-22 23:00:15.398 ERROR (MainThread) [homeassistant.components.automation.alarm_armed] Alarm Armed: Error executing script. Error for call_service at pos 1: Node(node_id=57) - NotFoundError: Configuration parameter with value ID 57-112-0-16 could not be found
2024-05-22 23:00:15.399 ERROR (MainThread) [homeassistant.components.automation.alarm_armed] Error while executing automation automation.alarm_armed: Node(node_id=57) - NotFoundError: Configuration parameter with value ID 57-112-0-16 could not be found
2024-05-22 23:29:47.113 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_113, Host is unreachable) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-22 23:42:57.034 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: 
2024-05-22 23:43:17.040 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: 
2024-05-22 23:43:27.145 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: [Errno 113] Connect call failed ('172.24.1.97', 9999)
2024-05-22 23:43:30.217 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: [Errno 113] Connect call failed ('172.24.1.97', 9999)
2024-05-22 23:44:22.032 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: 
2024-05-22 23:51:27.032 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 172.24.1.97 data: Unable to connect to the device: 172.24.1.97:9999: 
2024-05-23 00:18:52.124 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-23 01:08:07.136 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-23 01:57:32.150 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-23 02:47:07.162 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-23 03:00:00.480 WARNING (MainThread) [homeassistant.components.zwave_js.services] Entity switch.basement_dehumidifier is not a valid zwave_js entity
2024-05-23 03:00:00.480 ERROR (MainThread) [homeassistant.components.automation.ping_node] Ping Node: Error executing script. Invalid data for call_service at pos 1: No zwave_js nodes found for given targets
2024-05-23 03:00:00.481 ERROR (MainThread) [homeassistant.components.automation.ping_node] Error while executing automation automation.ping_node: No zwave_js nodes found for given targets
2024-05-23 03:36:52.175 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-23 04:26:47.187 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-23 04:53:38.503 WARNING (Thread-10) [pychromecast.socket_client] [Bens Bedroom speaker(172.24.1.87):8009] Heartbeat timeout, resetting connection
2024-05-23 04:53:49.744 ERROR (Thread-10) [pychromecast.socket_client] [Bens Bedroom speaker(172.24.1.87):8009] Failed to connect to service HostServiceInfo(host='172.24.1.87', port=8009), retrying in 5.0s
2024-05-23 05:16:52.198 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-23 06:07:07.211 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam
2024-05-23 06:57:32.223 ERROR (stream_worker) [homeassistant.components.stream.stream.camera.172_24_1_220] Error from stream worker: Error opening stream (ERRORTYPE_110, Operation timed out) rtsp://****:****@172.24.1.220:8554/test-cam

I have had this working in the paste but lately it has not been able to even complete a single backup. I have deleted and set up the connection again and it keeps on having trouble making backups.

What I keep seeing is that the initial backup will start put will not complete so it ends up causing a loop

sabeechen commented 1 month ago

From your supervisor logs:

Permission denied: '/data/mounts/HABackup/1aa11c50.tar

You will need to either resolve the issue with your network mount or store backups on the HA machine.