jotta / jotta-cli-issues

45 stars 1 forks source link

jottad intermittently stops syncing #186

Open m3h opened 1 year ago

m3h commented 1 year ago

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

jotta-cli release (jotta-cli version):

$ jotta-cli version
-------------------------------------------
jottad executable : /usr/bin/jottad
jottad appdata    : /home/reece/.jottad
jottad logfile    : /home/reece/.jottad/jottabackup.log
jottad version    : 0.14.60923
jotta-cli version : 0.14.60923
-------------------------------------------
OK

Description of problem: Every once in a while, I notice that jottad is not syncing my files anymore. If I look at the logs, there seems to be an authorization problem, followed by some websocket errors.

If I run systemctl --user restart jottad.service, that normally resolves the issue temporarily.

Expected:

jotta-cli status (jotta-cli status):

$ jotta-cli status
------------------------------------------------------------------------------
 Account   : EMAIL@ACCOUNT on Jottacloud
 Usage     : 0bytes / 0bytes 
 Device    : lenvious
------------------------------------------------------------------------------
 Sync      :
------------------------------------------------------------------------------
   Path      : /home/reece/jottacloud
   Files     : 16325 (15.04GiB)
   Mode      : listening to events
------------------------------------------------------------------------------
 No folders added to backup yet
------------------------------------------------------------------------------
OK

Relevant logs for the issue (~/.jottad/jottabackup.log or /var/lib/jotta/jottabackup.log )

pid:909 2022/12/10 15:52:48 Starting /usr/bin/jottad version 0.14.60923 as 1000(reece)/1000 (linux:amd64)
pid:909 2022/12/10 15:52:48 unix-socket.remove OK [/run/user/1000/jottad/jottad.socket]
pid:909 2022/12/10 15:52:48 jottad.init
pid:909 2022/12/10 15:52:48 jottad.init.appdata => /home/reece/.jottad
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/cldb (1666279752|16384)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/db (1670664458|32768)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/downloads
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/downloads/history (1670664458|32768)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/jottabackup.log (1670680368|5551147)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/localfiles (1670664464|4194304)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/nstore - (49391e25beea5f11afa0863f4f020698|1670663838|65536)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/settings.dat (1670664458|289)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/cl (1670544265|4194304)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/cursor
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/cursor/1e4b8090-781e-11ed-8084-bc832abc8080 (1670664750|4662602)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/cursor/cursor (1670664750|36)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/cursor/f19d75e0-7754-11ed-8084-bb612f0d8080 (1670564106|4662602)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/database (1670664745|5394432)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/database.tree (1670664745|4611818)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/left.final.tree (1670539404|1193118)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/left.initial.tree (1670539404|1193118)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/local.diff (1670540174|1383061)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/local.tree (1670540174|4710578)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/remote.diff (1670540174|72)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/remote.tree (1670664751|4662602)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/right.final.tree (1670539404|1138242)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/right.initial.tree (1670539404|1138242)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/sync/root (1670664464|22)
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/uploads
pid:909 2022/12/10 15:52:48 jottad.appdata  /home/reece/.jottad/uploads/history (1670664458|32768)
pid:909 2022/12/10 15:52:48 jottad.init.excludes @ v2 simpleGlob
pid:909 2022/12/10 15:52:48 Loading settings from /home/reece/.jottad/settings.dat
pid:909 2022/12/10 15:52:48 Unable to register device : Post "https://api.jottacloud.com/auth/v1/register": dial tcp: lookup api.jottacloud.com: Temporary failure in name resolution
pid:909 2022/12/10 15:52:48 jottad.init.cfg.downloadrate => 0B/s
pid:909 2022/12/10 15:52:48 jottad.init.cfg.uploadrate => 0B/s
pid:909 2022/12/10 15:52:48 jottad.init.user => [...] on [...] @ [...]
pid:909 2022/12/10 15:52:48 jottad.init.cfg.scaninterval =>  1h0m0s
pid:909 2022/12/10 15:52:48 jottad.init.cfg.backupmode => Interval
pid:909 2022/12/10 15:52:48 jottad.init.done => ok
pid:909 2022/12/10 15:52:48 jottad.startup
pid:909 2022/12/10 15:52:48 api.grpc.tls.active => /run/user/1000/jottad/jottad.socket
pid:909 2022/12/10 15:52:48 Error updating account info Post "https://api.jotta.cloud/customer/v2/get_customer": dial tcp: lookup api.jotta.cloud: Temporary failure in name resolution
pid:909 2022/12/10 15:52:48 error fetching account info: AccountInfo has updated yet
pid:909 2022/12/10 15:52:48 jottad.startup.old-userinfo => EMAIL@ACCOUNT Jottacloud
pid:909 2022/12/10 15:52:48 jottad.startup.updateUserinfo.err => AccountInfo has updated yet
pid:909 2022/12/10 15:52:48 jottad.confirm-remote failed => Post "https://api.jotta.cloud/device/v2/list": dial tcp: lookup api.jotta.cloud: Temporary failure in name resolution
pid:909 2022/12/10 15:52:48 jottad.startup => ok
pid:909 2022/12/10 15:52:48 running 1 delayed startup tasks
pid:909 2022/12/10 15:52:48 updating account info / customer true true
pid:909 2022/12/10 15:52:48 Error updating account info Post "https://api.jotta.cloud/customer/v2/get_customer": dial tcp: lookup api.jotta.cloud: Temporary failure in name resolution
pid:909 2022/12/10 15:52:48 waiting for internet connection. current error => Get "https://www.jottacloud.com/": dial tcp: lookup www.jottacloud.com: Temporary failure in name resolution
pid:909 2022/12/10 15:52:49 waiting for remote session confirmation
pid:909 2022/12/10 15:52:54 running 4 delayed startup tasks
pid:909 2022/12/10 15:52:54 jottad.sync.setup-check: /home/reece/.jottad/sync/root
pid:909 2022/12/10 15:52:54 jottad.sync.initalizing => [/home/reece/jottacloud]
pid:909 2022/12/10 15:52:54 jottad.sync.initalized => [/home/reece/jottacloud]
pid:909 2022/12/10 15:52:55 fsw: watch  /home/reece/jottacloud
pid:909 2022/12/10 15:52:55 jottad.jnote.connected @ 1e4b8090-781e-11ed-8084-bc832abc8080
pid:909 2022/12/10 15:52:55 couldn't extract JSON from suspected proto err
pid:909 2022/12/10 15:52:55 Error updating account info Error code: 401: {"message":"Invalid authorization token","xid":"...","errors":[{"type":"UNAUTHENTICATED","code":3}]}
pid:909 2022/12/10 15:53:19 waiting for remote session confirmation
pid:909 2022/12/10 15:53:21 GET https://repo.jotta.us/archives/VERSION 200
pid:909 2022/12/10 15:57:34 unable to translate path => Cannot translate internal device ID 
pid:909 2022/12/10 16:03:15 websocket recv: read tcp 192.168.129.2:38426->185.179.128.30:443: i/o timeout
pid:909 2022/12/10 16:03:15 websocket read exit
pid:909 2022/12/10 16:03:15 jottad.jnote.disconnect => read tcp 192.168.129.2:38426->185.179.128.30:443: i/o timeout
pid:909 2022/12/10 16:03:26 jottad.jnote.connected @ 1e4b8090-781e-11ed-8084-bc832abc8080

Traceback

Additional info: I installed jotta-cli using the Arch AUR package.