[gpsd-users] After RELEASE_TIME device closed, gpsd reports error on next open

[Bin Guo]

Hi,

  Sorry for the long post. This is my first time using gpsd.  I was trying to talk to a U-Box NEO-M8T via UART (no antenna yet, so no fix). Initially connecting with gpspipe, I can see gpsd switched driver to ubox:

root@5gsb:~# /usr/sbin/gpsd -F /var/run/gpsd.sock -P /var/run/gpsd.pid /dev/ttyPS1 -N -D4

gpsd:PROG: control socket opened at /var/run/gpsd.sock

gpsd:INFO: launching (Version 3.19.1~dev)

gpsd:INFO: listening on port gpsd

...

gpsd:PROG: switching to match packet type 11: b5620600140001000000c008000080250000070002000000000091af

gpsd:PROG: switch_driver(u-blox) called...

gpsd:PROG: selecting u-blox driver...

gpsd:INFO: /dev/ttyPS1 identified as type u-blox, 1 sec @ 9600bps

...

                root@5gsb:~# gpspipe -r -n 10

                {"class":"VERSION","release":"3.19.1~dev","rev":"release-3.19-452-g7832f9507","proto_major":3,"proto_minor":14}

                {"class":"DEVICES","devices":[{"class":"DEVICE","path":"/dev/ttyPS1","activated":"2019-09-04T10:21:23.290Z","native":0,"bps":9600,"parity":"N","stopbits":1,"cycle":1.00}]}

                {"class":"WATCH","enable":true,"json":false,"nmea":true,"raw":0,"scaled":false,"timing":false,"split24":false,"pps":false}

                $GNTXT,01,01,01,PASH inv format*28

                $GNTXT,01,01,01,PGRM inv format*2A

                $GNTXT,01,01,01,PSRF inv format*35

                $GNTXT,01,01,01,PFEC inv format*32

                $GNTXT,01,01,01,PTNL inv format*24

                $GNRMC,212132.00,V,,,,,,,040919,,,N*67

                $GNVTG,,,,,,,,,N*2E

 But after idle timeout:

gpsd:PROG: UBX-NAV-TIMEGPS

gpsd:PROG: UBX: cycle end 120

gpsd:WARN: cycle-start detector failed.

gpsd:PROG: UBX-NAV-SOL

gpsd:PROG: UBX-NAV-PVT

gpsd:PROG: UBX-NAV-DOP

gpsd:PROG: UBX-NAV-TIMEGPS

gpsd:PROG: device 0 closed

gpsd:PROG: => GPS: UBX class: 06, id: 00, len: 20, crc: a1af

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 0f4a

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 114e

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 1250

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 2b82

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 3ba2

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 40ac

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 3da6

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 6c04

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 0422

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: fb10

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: fd14

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 021e

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: fe16

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: ff18

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 001a

gpsd:PROG: => GPS: UBX class: 06, id: 01, len: 3, crc: 0320

gpsd:INFO: closing GPS=/dev/ttyPS1 (7)

gpsd:PROG: no /etc/gpsd/device-hook present, skipped running DEACTIVATE hook

next gpspipe call shows that gpsd never gets back to UBX-... mode but gets stuck in a bad state:

gpsd:CLIENT: => client(0): {"class":"VERSION","release":"3.19.1~dev","rev":"release-3.19-452-g7832f9507","proto_major":3,"proto_minor":14}\x0d\x0a

gpsd:PROG: checking client(0)

gpsd:CLIENT: <= client(0): ?WATCH={"enable":true,"nmea":true};\x0a

gpsd:PROG: no /etc/gpsd/device-hook present, skipped running ACTIVATE hook

gpsd:INFO: SER: opening GPS data source type 2 at '/dev/ttyPS1'

gpsd:INFO: SER: speed 9600, 8N1

gpsd:PROG: Probing "Garmin USB binary" driver...

gpsd:PROG: Probe not found "Garmin USB binary" driver...

gpsd:PROG: Probing "GeoStar" driver...

gpsd:PROG: Sent GeoStar packet id 0xc1

gpsd:PROG: Probe not found "GeoStar" driver...

gpsd:PROG: Probing "Trimble TSIP" driver...

gpsd:INFO: SER: speed 9600, 8O1

gpsd:INFO: SER: speed 9600, 8N1

gpsd:PROG: Probe not found "Trimble TSIP" driver...

gpsd:PROG: Probing "iSync" driver...

gpsd:INFO: SER: speed 9600, 8N1

gpsd:INFO: SER: speed 9600, 8N1

gpsd:PROG: Probe not found "iSync" driver...

gpsd:PROG: no probe matched...

gpsd:INFO: gpsd_activate(2): activated GPS (fd 7)

gpsd:CLIENT: => client(0): {"class":"DEVICES","devices":[{"class":"DEVICE","path":"/dev/ttyPS1","driver":"u-blox","subtype":"SW EXT CORE 3.01 (111141),HW 00080000,ROM BASE 2.01 (75331),FWVER=TIM 1.10,PROTVER=22.00,MOD=NEO-M8T-0,FIS=0xEF4015 (100111),GP","activated":"2019-09-04T10:24:10.946Z","flags":1,"native":1,"bp

s":9600,"parity":"N","stopbits":1,"cycle":1.00,"mincycle":0.25}]}\x0d\x0a{"class":"WATCH","enable":true,"json":false,"nmea":true,"raw":0,"scaled":false,"timing":false,"split24":false,"pps":false}\x0d\x0a

gpsd:PROG: xPGSV: part 1 of 1, last_gsv_talker '0'  last_gsv_sigid 0

gpsd:PROG: xPGSV: new part 1, last_gsv_talker '0', zeroing

gpsd:WARN: xxGSV: Satellite data no good (1 of 1).

gpsd:CLIENT: => client(0): $GPGSV,1,1,01,15,,,27*79\x0d\x0a

gpsd:PROG: xLGSV: part 1 of 1, last_gsv_talker '0x50'  last_gsv_sigid 0

gpsd:WARN: xxGSV: Satellite data no good (1 of 1).

gpsd:CLIENT: => client(0): $GLGSV,1,1,00*65\x0d\x0a

gpsd:PROG: GNGLL starts a reporting cycle. lasttag 19

gpsd:PROG: tagged GBS as a cycle ender. 0x80000

gpsd:CLIENT: => client(0): $GNGLL,,,,,212419.00,V,N*59\x0d\x0a

gpsd:PROG: Changed mask: {ONLINE|TIME|STATUS|MODE|PACKET|CLEAR|NTPTIME} with reliable cycle detection

gpsd:CLIENT: => client(0): $GNGST,212419.00,0.0000,,,,288675,288675,288675*72\x0d\x0a

gpsd:PROG: Changed mask: {ONLINE|PACKET|GST} with reliable cycle detection

gpsd:CLIENT: => client(0): $GNZDA,212419.00,04,09,2019,00,00*72\x0d\x0a

gpsd:PROG: Changed mask: {ONLINE|TIME|PACKET|NTPTIME} with reliable cycle detection

gpsd:PROG: GNGBS ends a reporting cycle.

gpsd:CLIENT: => client(0): $GNGBS,212419.00,,,,,,,*7C\x0d\x0a

gpsd:PROG: Changed mask: {ONLINE|HERR|VERR|PACKET|REPORT} with reliable cycle detection

gpsd:PROG: time to report a fix

gpsd:PROG: GNRMC is just after a cycle ender.

gpsd:CLIENT: => client(0): $GNRMC,212420.00,V,,,,,,,040919,,,N*61\x0d\x0a

gpsd:PROG: Changed mask: {ONLINE|STATUS|MODE|PACKET} with reliable cycle detection

gpsd:PROG: checking client(0)

gpsd:INFO: detaching ::1 (sub 0, fd 6) in detach_client

gpsd:PROG: device 0 (fd 7) released

gpsd:WARN: cycle-start detector failed.

gpsd:PROG: GNVTG is just after a cycle ender.

gpsd:PROG: GNGGA starts a reporting cycle. lasttag 19

gpsd:PROG: xxGSA sets mode 1

gpsd:PROG: xxGSA: mask 0x402

gpsd:PROG: xxGSA sets mode 1

gpsd:PROG: xxGSA: mask 0x100000000c00

gpsd:PROG: xPGSV: part 1 of 1, last_gsv_talker '0'  last_gsv_sigid 0

gpsd:PROG: xPGSV: new part 1, last_gsv_talker '0', zeroing

gpsd:WARN: xxGSV: Satellite data no good (1 of 1).

gpsd:PROG: xLGSV: part 1 of 1, last_gsv_talker '0x50'  last_gsv_sigid 0

gpsd:WARN: xxGSV: Satellite data no good (1 of 1).

gpsd:PROG: GNGBS ends a reporting cycle.

gpsd:PROG: GNRMC is just after a cycle ender.

gpsd:WARN: cycle-start detector failed.

gpsd:PROG: GNVTG is just after a cycle ender.

gpsd:PROG: GNGGA starts a reporting cycle. lasttag 19

gpsd:PROG: xxGSA sets mode 1

gpsd:PROG: xxGSA: mask 0x402

gpsd:PROG: xxGSA sets mode 1

gpsd:PROG: xxGSA: mask 0x100000000c00

gpsd:PROG: xPGSV: part 1 of 1, last_gsv_talker '0'  last_gsv_sigid 0

gpsd:PROG: xPGSV: new part 1, last_gsv_talker '0', zeroing

gpsd:WARN: xxGSV: Satellite data no good (1 of 1).

gpsd:PROG: xLGSV: part 1 of 1, last_gsv_talker '0x50'  last_gsv_sigid 0

gpsd:WARN: xxGSV: Satellite data no good (1 of 1).

gpsd:PROG: GNGBS ends a reporting cycle.

gpsd:PROG: GNRMC is just after a cycle ender.

gpsd:WARN: cycle-start detector failed.

gpsd:PROG: GNVTG is just after a cycle ender.

gpsd:PROG: GNGGA starts a reporting cycle. lasttag 19

This can only be recovered by restarting gpsd (I did try ubx-tool -p RESET ... no help). I compiled from git, but similar problems occurred for 3.16 and 3.19 releases.

We might be able to poll gpsd more often, but this seems like a dangerous problem. Is there anything apparent that I'm missing?

Thanks!

-- 

Bin