jotta / jotta-cli-issues

45 stars 1 forks source link

Backup not performed: jottad.auth.err => dial tcp: lookup id.jottacloud.com: no such host. #112

Closed Nordskog closed 4 years ago

Nordskog commented 4 years ago

Make sure you are running the latest version of jotta-cli before reporting an issue.

jotta-cli release (jotta-cli version):

jottad executable : jottad appdata : C:\WINDOWS\system32\config\systemprofile.jottad jottad version : 0.6.24251 jotta-cli version : 0.6.24251

Description of problem:

Neither scans nor backups are performed. Perpetual "Waiting for scan to start" regardless of interval setting or manually calling jotta-cli scan, but pausing and resuming initially helped. After a longer interval was configured ( 12 hours ) and being left to its own devices, auth errors boiling down to dial tcp: lookup repo.jotta.us: no such host began to appear every 24 hours, with no scans ever being performed.

jotta-cli archive still works fine

Expected:

Added folders should be scanned and backed up at the configured interval, logs should not indicate that it is unable to authenticated due to being unable to reach id.jottacloud.com.

jotta-cli status (jotta-cli status):

------------------------------------------------------------------------------
 Account   : ${Account name} on Jottacloud
 Usage     : 88.48GiB / ( Unlimited )
 Device    : ${device name}
 Backups   :
------------------------------------------------------------------------------
   Path      : C:/Users/Roughy/Desktop/txt
   Status    : Waiting for scan to start

------------------------------------------------------------------------------
   Path      : C:/Users/Roughy/VscodeWorkspace
   Status    : Waiting for scan to start

------------------------------------------------------------------------------
   Path      : C:/Users/Roughy/androidWorkspace
   Status    : Waiting for scan to start

------------------------------------------------------------------------------
   Path      : C:/Users/Roughy/workspace
   Status    : Waiting for scan to start

------------------------------------------------------------------------------
OK

Relevant logs for the issue (cat ~/.jottad/jottabackup.log)


### Fresh install, some folders added initially were scanned and backed up as exepceted

pid:13632 2020/05/08 00:55:17 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 01:18:18 Backup starting of [C:/Users/Roughy/androidWorkspace]
pid:13632 2020/05/08 01:18:18 Running scan of C:/Users/Roughy/androidWorkspace
pid:13632 2020/05/08 01:18:29 Scan of C:/Users/Roughy/androidWorkspace completed in 10.630999s. Found 49363 files 5.14GiB 
pid:13632 2020/05/08 01:18:29 Backup completed of [C:/Users/Roughy/androidWorkspace] completed in 10.6469969s [49363 files 5.14GiB]
pid:13632 2020/05/08 02:18:33 Backup starting of [C:/Users/Roughy/androidWorkspace]
pid:13632 2020/05/08 02:18:33 Running scan of C:/Users/Roughy/androidWorkspace
pid:13632 2020/05/08 02:18:40 Scan of C:/Users/Roughy/androidWorkspace completed in 7.6880007s. Found 49363 files 5.14GiB 
pid:13632 2020/05/08 02:18:40 Backup completed of [C:/Users/Roughy/androidWorkspace] completed in 7.6989848s [49363 files 5.14GiB]
pid:13632 2020/05/08 02:55:17 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 03:18:48 Backup starting of [C:/Users/Roughy/androidWorkspace]
pid:13632 2020/05/08 03:18:48 Running scan of C:/Users/Roughy/androidWorkspace
pid:13632 2020/05/08 03:18:58 Scan of C:/Users/Roughy/androidWorkspace completed in 9.622001s. Found 49363 files 5.14GiB 
pid:13632 2020/05/08 03:18:58 Backup completed of [C:/Users/Roughy/androidWorkspace] completed in 9.6439989s [49363 files 5.14GiB]
pid:13632 2020/05/08 03:35:17 Added C:/Users/Roughy/workspace
pid:13632 2020/05/08 03:35:17 Backup queued for C:/Users/Roughy/workspace
pid:13632 2020/05/08 03:35:17 Backup starting of [C:/Users/Roughy/workspace]
pid:13632 2020/05/08 03:35:17 Running scan of C:/Users/Roughy/workspace
pid:13632 2020/05/08 03:36:58 Scan of C:/Users/Roughy/workspace completed in 1m40.2878965s. Found 357156 files 1.50GiB 
pid:13632 2020/05/08 03:37:45 Added C:/Users/Roughy/VscodeWorkspace
pid:13632 2020/05/08 03:37:45 Backup queued for C:/Users/Roughy/VscodeWorkspace
pid:13632 2020/05/08 03:38:31 Added C:/Users/Roughy/Desktop/txt
pid:13632 2020/05/08 03:38:31 Backup queued for C:/Users/Roughy/Desktop/txt

### Newly added folder not scanned, jotta-cli scan does not trigger scan. Set short interval in hopes of having to execute but it never did.

pid:13632 2020/05/08 03:39:49 cfg.update.scaninterval 1h0m0s => 12h
pid:13632 2020/05/08 03:43:24 cfg.update.scaninterval 12h => 2m 
pid:13632 2020/05/08 03:48:22 cfg.update.scaninterval 2m => 12h

### Pausing and resuming seemed to kick it into action

pid:13632 2020/05/08 03:51:16 Pausing for 1h0m0s. Resume @ 2020-05-08 04:51:16.262972 +0200 CEST m=+21363.225310801     
pid:13632 2020/05/08 03:51:16 Stopping scheduled backup of C:/Users/Roughy/androidWorkspace
pid:13632 2020/05/08 03:51:27 Resuming
pid:13632 2020/05/08 03:51:27 Backup starting of [C:/Users/Roughy/workspace]
pid:13632 2020/05/08 03:51:27 Running scan of C:/Users/Roughy/workspace
pid:13632 2020/05/08 03:52:44 Scan of C:/Users/Roughy/workspace completed in 1m16.8429433s. Found 357156 files 1.50GiB 
pid:13632 2020/05/08 04:55:17 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 05:47:23 Backup completed of [C:/Users/Roughy/workspace] completed in 1h55m55.8992054s [357156 files 1.50GiB]
pid:13632 2020/05/08 05:47:24 Backup completed of [C:/Users/Roughy/workspace] completed in 2h12m6.3298986s [357156 files 1.50GiB]
pid:13632 2020/05/08 05:47:24 Backup starting of [C:/Users/Roughy/VscodeWorkspace]
pid:13632 2020/05/08 05:47:24 Running scan of C:/Users/Roughy/VscodeWorkspace
pid:13632 2020/05/08 05:47:26 Scan of C:/Users/Roughy/VscodeWorkspace completed in 2.2939913s. Found 6254 files 109.91MiB 
pid:13632 2020/05/08 05:47:33 Backup starting of [C:/Users/Roughy/Desktop/txt]
pid:13632 2020/05/08 05:47:33 Running scan of C:/Users/Roughy/Desktop/txt
pid:13632 2020/05/08 05:47:36 Scan of C:/Users/Roughy/Desktop/txt completed in 3.5710008s. Found 1981 files 1.27GiB 
pid:13632 2020/05/08 05:49:31 Backup completed of [C:/Users/Roughy/Desktop/txt] completed in 1m58.3197201s [1981 files 1.27GiB]

### Computer shut down until next day here

pid:13632 2020/05/08 15:53:01 jottad.auth.err => Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:02 Backup starting of [C:/Users/Roughy/androidWorkspace]
pid:13632 2020/05/08 15:53:02 Running scan of C:/Users/Roughy/androidWorkspace
pid:13632 2020/05/08 15:53:03 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 allocate: 5fdfe02e09519cfe43452b7332f3559b (/jfs/DESKTOP-C4K36BF/androidWorkspace/tlmarker/phoenix-launcher/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/tlmarker/phoenix-launcher/.git/FETCH_HEAD","Size":237,"Modified":"2020-05-08T06:08:28.9222067+02:00","Created":"2019-12-20T04:44:07.5099393+01:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 allocate: e0c9ea661b9358a93f4fd0fedcb8cd73 (/jfs/DESKTOP-C4K36BF/androidWorkspace/tlmarker/phoenix-installer/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/tlmarker/phoenix-installer/.git/FETCH_HEAD","Size":108,"Modified":"2020-05-08T06:08:31.6004057+02:00","Created":"2019-12-20T04:43:49.5747543+01:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 allocate: 2d727c820dc384a104ad4eb186153279 (/jfs/DESKTOP-C4K36BF/androidWorkspace/tlmarker/phoenix-admin/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:03 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/tlmarker/phoenix-admin/.git/FETCH_HEAD","Size":226,"Modified":"2020-05-08T06:09:18.4642025+02:00","Created":"2019-12-25T02:38:47.3543508+01:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:04 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 15:53:04 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:04 Error getting user token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host rescheduling check in 10s
pid:13632 2020/05/08 15:53:04 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:04 allocate: 409ec3b402e845b8d40983cac4cb4010 (/jfs/DESKTOP-C4K36BF/androidWorkspace/tabristhing/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:04 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:04 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/tabristhing/.git/FETCH_HEAD","Size":773,"Modified":"2020-05-08T06:09:15.8851951+02:00","Created":"2020-01-13T23:15:38.9169808+01:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:05 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:05 allocate: 7553a26c8a21b39fe000ba85a0789182 (/jfs/DESKTOP-C4K36BF/androidWorkspace/sitedude/DollarAdmiral-master/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:05 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:05 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/sitedude/DollarAdmiral-master/.git/FETCH_HEAD","Size":220,"Modified":"2020-05-08T06:09:22.7416167+02:00","Created":"2019-11-17T22:45:07.1740333+01:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 allocate: 60a7eb5ad7bf7fa0bb9cbd89fbe0859e (/jfs/DESKTOP-C4K36BF/androidWorkspace/pybridge_nasa/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/pybridge_nasa/.git/FETCH_HEAD","Size":218,"Modified":"2020-05-08T06:08:54.8398609+02:00","Created":"2019-05-17T02:16:30.7784848+02:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 allocate: 685e1fae0e585e18ff6a661331452ca4 (/jfs/DESKTOP-C4K36BF/androidWorkspace/lnwallet/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/lnwallet/.git/FETCH_HEAD","Size":336,"Modified":"2020-05-08T06:08:49.4875351+02:00","Created":"2019-12-01T03:05:41.1060848+01:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 allocate: 738d0bd4b034443908edbd41fc6a90cd (/jfs/DESKTOP-C4K36BF/androidWorkspace/barcode-reader/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:06 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/barcode-reader/.git/FETCH_HEAD","Size":104,"Modified":"2020-05-08T06:08:44.2070397+02:00","Created":"2019-11-21T19:31:26.5801568+01:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:08 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:08 allocate: d41d8cd98f00b204e9800998ecf8427e (/jfs/DESKTOP-C4K36BF/androidWorkspace/VisualBatterystats/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:08 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:08 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/VisualBatterystats/.git/FETCH_HEAD","Size":0,"Modified":"2020-05-08T06:08:24.7131059+02:00","Created":"2019-05-17T02:24:01.615637+02:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:12 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:12 allocate: 25e98ccea0b4ea613a435b96cdd52634 (/jfs/DESKTOP-C4K36BF/androidWorkspace/SwiftKeyExi/.git/sourcetreeconfig.json) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:12 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:12 Error uploading {"Name":"sourcetreeconfig.json","Path":"C:/Users/Roughy/androidWorkspace/SwiftKeyExi/.git/sourcetreeconfig.json","Size":916,"Modified":"2020-05-08T06:18:07.6880011+02:00","Created":"2019-05-17T02:19:25.4641138+02:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:12 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:12 allocate: 3dc883912ffbe959804f0bb67ab4c8f7 (/jfs/DESKTOP-C4K36BF/androidWorkspace/SwiftKeyExi/.git/FETCH_HEAD) => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:12 Error allocating token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:12 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/SwiftKeyExi/.git/FETCH_HEAD","Size":212,"Modified":"2020-05-08T06:08:57.4108603+02:00","Created":"2019-05-17T02:19:25.4271251+02:00","Checksum":"","State":"Added"} => token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/08 15:53:13 jottad.auth.err => <nil>  => RESOLVED

### Despite the auth errors, all the folders are scanned and at least partially(?) backed up.

pid:13632 2020/05/08 15:53:21 Scan of C:/Users/Roughy/androidWorkspace completed in 18.881071s. Found 49363 files 5.14GiB 
pid:13632 2020/05/08 15:53:35 allocate: 52f6cdfdcb6d7326b825c72fa746f50c (/jfs/DESKTOP-C4K36BF/androidWorkspace/SimpleProtocolPlayer/.git/FETCH_HEAD) => Post https://api.jottacloud.com/files/v1/allocate: read tcp 192.168.1.110:61628->185.179.130.26:443: wsarecv: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.
pid:13632 2020/05/08 15:53:35 Error allocating Post https://api.jottacloud.com/files/v1/allocate: read tcp 192.168.1.110:61628->185.179.130.26:443: wsarecv: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.
pid:13632 2020/05/08 15:53:35 Error uploading {"Name":"FETCH_HEAD","Path":"C:/Users/Roughy/androidWorkspace/SimpleProtocolPlayer/.git/FETCH_HEAD","Size":110,"Modified":"2020-05-08T06:09:05.4014275+02:00","Created":"2019-05-17T02:17:49.6692011+02:00","Checksum":"","State":"Added"} => Post https://api.jottacloud.com/files/v1/allocate: read tcp 192.168.1.110:61628->185.179.130.26:443: wsarecv: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.
pid:13632 2020/05/08 15:53:35 Backup completed of [C:/Users/Roughy/androidWorkspace] completed in 32.7673664s [49363 files 5.14GiB]
pid:13632 2020/05/08 15:53:35 Backup starting of [C:/Users/Roughy/workspace]
pid:13632 2020/05/08 15:53:35 Running scan of C:/Users/Roughy/workspace
pid:13632 2020/05/08 15:54:37 Scan of C:/Users/Roughy/workspace completed in 1m1.7068476s. Found 357156 files 1.50GiB 
pid:13632 2020/05/08 15:54:37 Backup completed of [C:/Users/Roughy/workspace] completed in 1m1.8158467s [357156 files 1.50GiB]
pid:13632 2020/05/08 16:55:17 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 17:47:33 Backup starting of [C:/Users/Roughy/VscodeWorkspace]
pid:13632 2020/05/08 17:47:33 Running scan of C:/Users/Roughy/VscodeWorkspace
pid:13632 2020/05/08 17:47:33 Scan of C:/Users/Roughy/VscodeWorkspace completed in 686.0004ms. Found 6254 files 109.91MiB 
pid:13632 2020/05/08 17:47:33 Backup completed of [C:/Users/Roughy/VscodeWorkspace] completed in 697.972ms [6254 files 109.91MiB]
pid:13632 2020/05/08 17:47:37 Backup starting of [C:/Users/Roughy/Desktop/txt]
pid:13632 2020/05/08 17:47:37 Running scan of C:/Users/Roughy/Desktop/txt
pid:13632 2020/05/08 17:47:37 Scan of C:/Users/Roughy/Desktop/txt completed in 321.9992ms. Found 1981 files 1.27GiB 
pid:13632 2020/05/08 18:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 20:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 22:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/09 00:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/09 02:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/09 04:55:19 GET https://repo.jotta.us/archives/VERSION 200

### The below repeats once every 24 hours. No further scans or backups are performed. jotta-cli archive works fine

pid:13632 2020/05/09 15:04:38 jottad.auth.err => Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/09 15:04:40 Error checking for new versions Get https://repo.jotta.us/archives/VERSION: dial tcp 185.179.128.26:443: connectex: An attempt was made to access a socket in a way forbidden by its access permissions.
pid:13632 2020/05/09 15:04:40 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/09 15:04:40 Error getting user token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host rescheduling check in 10s
pid:13632 2020/05/09 15:05:02 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/09 15:05:02 ensure failed due to error token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host
pid:13632 2020/05/09 15:05:02 Error getting user token: request: Post https://id.jottacloud.com/auth/realms/jottacloud/protocol/openid-connect/token: dial tcp: lookup id.jottacloud.com: no such host rescheduling check in 10s
pid:13632 2020/05/09 15:05:15 jottad.auth.err => <nil>  => RESOLVED
pid:13632 2020/05/08 18:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 20:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/08 22:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/09 00:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/09 02:55:19 GET https://repo.jotta.us/archives/VERSION 200
pid:13632 2020/05/09 04:55:19 GET https://repo.jotta.us/archives/VERSION 200

Additional info:

Running windows 10 1909 Windows firewall disabled, using Netlimiter instead ( jotta.exe, jottad.exe both allowed).

Fresh jotta-cli setup on new machine. First two folders added were scanned and backed up immediately, but third folder was not. Neither jotta-cli scan nor setting the interval to 2 minutes and waiting had any effect. Pausing and resuming jotta-cli did eventually get it to scan the new folders.

Interval was set to 12 hours, and after the computer was shut down the folders were successfully scanned and backed up the next day ( May 8th ), despite the jottad.auth.err errors in the log.

The computer was again shut down and resumed the following day ( May 9th ), but the logs are now filled with the aforementioned jotta.auth.err messages. The logs now only contain this message repeated once a day, with no scans performed as of this issue being submitted.

jotta-cli archive still works fine. No problem pinging id.jottacloud.com in shell.

Nordskog commented 4 years ago

Let it fester for a while and it seems to be running fine now, or at least backing up stuff every now and then like it's supposed to.

¯_(ツ)_/¯