sync-client crash : JSONDecodeError:

[Vaishali Dhakate]

Aug 25 08:09:09.253766 VM syncvm (7): Starting sync-client-daemon
Aug 25 08:09:09.483111 rpc-proxy: incoming connection from domain 7, port 0x79cc1f5b9, fd is 9
Aug 25 08:09:09.483891 rpc-proxy: authenticating client <argo: 9> ArtefactSource {sourceDomainID = 7, sourceUuid = Just 2ff51f8b-e615-4116-b132-0594bb469db0, sourceIsStubdom = False}
Aug 25 08:09:09.529656 VM syncvm (7): sync-client-daemon: starting: 1 synchronizer
Aug 25 08:09:09.530008 VM syncvm (7): sync-client-daemon: klc: starting sync-client
Aug 25 08:09:09.531620 VM syncvm (7): INIT: no more processes left in this runlevel
Aug 25 08:09:09.531839 VM syncvm (7): init: no more processes left in this runlevel
Aug 25 08:09:09.675931 VM syncvm (7): sync-client: client starting
Aug 25 08:09:09.684667 rpc-proxy: incoming connection from domain 7, port 0x7805c01f8, fd is 9
Aug 25 08:09:09.685715 rpc-proxy: authenticating client <argo: 9> ArtefactSource {sourceDomainID = 7, sourceUuid = Just 2ff51f8b-e615-4116-b132-0594bb469db0, sourceIsStubdom = False}
Aug 25 08:09:09.707047 icbinn_svc: Info:svc_main.c:main:285:in child, index of accepted connection = 0
Aug 25 08:09:09.707181 icbinn_svc: Info:svc_main.c:main:294:client dom_id = 7
Aug 25 08:09:09.707353 VM syncvm (7): sync-client: calling icbinn at (0, 4878)
Aug 25 08:09:09.707367 VM syncvm (7): sync-client: successfully contacted icbinn server for /storage/sync/klc/
Aug 25 08:09:09.707371 VM syncvm (7): sync-client: calling icbinn at (0, 4879)
Aug 25 08:09:09.708843 icbinn_svc: Info:svc_main.c:main:285:in child, index of accepted connection = 1
Aug 25 08:09:09.708939 icbinn_svc: Info:svc_main.c:main:294:client dom_id = 7
Aug 25 08:09:09.709758 VM syncvm (7): sync-client: successfully contacted icbinn server for /config/sync/klc
Aug 25 08:09:09.709768 VM syncvm (7): sync-client: contacted icbinn
Aug 25 08:09:09.754693 VM syncvm (7): sync-client: GET https://192.168.1.2/hello/1
Aug 25 08:09:09.788786 icbinn_svc: Info:svc_main.c:main:308:icbinn-path number 1 is /config/sync/klc
Aug 25 08:09:09.794657 icbinn_svc: Info:svc_main.c:main:308:icbinn-path number 0 is /storage/sync/klc/

Dear openxt Team,

First Thank you for the help and support so far. Its been a very great learning experience to work on this project.

I am making good progress and now surprisingly I have a very different error.  I was able to connect to the sync-server on the Redhat download update and everything went well . But this time around, I get this error. It will be great help if someone has any pointers/clues about what this is.

Warm Regards.

Vaishali.

Aug 25 08:09:09.999745 VM syncvm (7): sync-client: curl stdout 500

Aug 25 08:09:10.011235 VM syncvm (7): sync-client: crash: Traceback (most recent call last):
Aug 25 08:09:10.011253 VM syncvm (7): sync-client: crash:   File "/usr/lib/python3.8/site-packages/sync_client/client.py", line 1506, in main
Aug 25 08:09:10.011258 VM syncvm (7): sync-client: crash:     hello = loads(server.operation('get', 'hello/1'))
Aug 25 08:09:10.011262 VM syncvm (7): sync-client: crash:   File "/usr/lib/python3.8/json/__init__.py", line 357, in loads
Aug 25 08:09:10.011267 VM syncvm (7): sync-client: crash:     return _default_decoder.decode(s)
Aug 25 08:09:10.011272 VM syncvm (7): sync-client: crash:   File "/usr/lib/python3.8/json/decoder.py", line 337, in decode
Aug 25 08:09:10.011279 VM syncvm (7): sync-client: crash:     obj, end = self.raw_decode(s, idx=_w(s, 0).end())
Aug 25 08:09:10.011284 VM syncvm (7): sync-client: crash:   File "/usr/lib/python3.8/json/decoder.py", line 355, in raw_decode
Aug 25 08:09:10.011288 VM syncvm (7): sync-client: crash:     raise JSONDecodeError("Expecting value", s, err.value) from None
Aug 25 08:09:10.011293 VM syncvm (7): sync-client: crash: json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
Aug 25 08:09:10.011297 VM syncvm (7): sync-client: crash:
Aug 25 08:09:10.023603 icbinn_svc: Fatal:svc_main.c:check_client_alive:52:client seems to have closed the connection, exiting
Aug 25 08:09:10.023604 icbinn_svc: Fatal:svc_main.c:check_client_alive:52:client seems to have closed the connection, exiting
Aug 25 08:09:10.025205 VM syncvm (7): sync-client-daemon: klc: sync-client failed (exit status 3)
Aug 25 08:09:10.261263 VM syncvm (7): rsyslogd: [origin software="rsyslogd" swVersion="8.2002.0" x-pid="501" x-info="https://www.rsyslog.com"] start
Aug 25 08:09:19.315468 kernel:[  560.835202] FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.

[Jason Andryuk]

Hi, Vaishali,

On Thu, Aug 25, 2022 at 4:24 AM Vaishali Dhakate
<vaishali...@networkmarvels.com> wrote:
...

> Aug 25 08:09:09.754693 VM syncvm (7): sync-client: GET https://192.168.1.2/hello/1
> Aug 25 08:09:09.788786 icbinn_svc: Info:svc_main.c:main:308:icbinn-path number 1 is /config/sync/klc
> Aug 25 08:09:09.794657 icbinn_svc: Info:svc_main.c:main:308:icbinn-path number 0 is /storage/sync/klc/
> Dear openxt Team,
> First Thank you for the help and support so far. Its been a very great learning experience to work on this project.
> I am making good progress and now surprisingly I have a very different error. I was able to connect to the sync-server on the Redhat download update and everything went well . But this time around, I get this error. It will be great help if someone has any pointers/clues about what this is.
> Warm Regards.
> Vaishali.
>
> Aug 25 08:09:09.999745 VM syncvm (7): sync-client: curl stdout 500

I think this line is the logging at sync_client/client.py:383, and
curl is configured to write the HTTP Status code with ['--write-out',
'%{http_code}']. HTTP 500 is Status Code "Internal Server Error", so
that points toward server side error. Check your logs there.

sync_client/client.py:390 should probably be expanded to also check
for '5' for server-side errors.

Regards,
Jason