Starnote serial busy-loop logs - before and after fix

notecard_usb_0.txt

[09:55:59.017] R990:09.58 trace is now on
[09:55:59.045] R990:09.59 firmware size:921227 max:1015808 (94581 remaining)
[09:56:00.694] R990:11.26 trace options:
[09:56:00.719]   requested: +star
[09:56:00.720]         now: comm,sync,mem,gps,star
[09:56:10.791] {}
[09:56:10.817] S990:21.36 transport: set to ntn:
[09:56:10.844] S990:21.39 penalty: service penalty cleared: transport switched
[09:56:10.880] S990:21.41 sync: connect requested by explicit sync request (PACKET)
[09:56:10.928] S990:21.49 connect: 4.75V 26.00C before comms power-on (2026-02-07T09:56:01Z UTC)
[09:56:11.994] S990:22.54 ntn: idle {disconnected} {ntn-idle}{ntn-unknown-location}
[09:56:12.484] S990:23.05 ntn: waiting for wireless service {wait-service} {connecting} powering up {ntn-connecting}{ntn-unknown-location}
[09:56:12.845] R990:23.41 star: gps: powered on
[09:56:14.146] R990:24.71 star: 
[09:56:14.177] === SERIAL STATS (last 10 sec) ===
[09:56:14.338] R990:24.88 star: notecard: polls=7226 (722.6/s) T=6627 F=599 term=3761 lock=2850 nodata=599 bytes=199 lines=14 avgMs=0
[09:56:14.467] R990:25.03 star: gps: polls=7228 (722.8/s) T=505 F=6723 term=501 lock=0 nodata=6723 bytes=37 lines=2 avgMs=0
[09:56:14.618] R990:25.18 star: modem: polls=7230 (723.0/s) T=0 F=7230 term=0 lock=0 nodata=7230 bytes=0 lines=0 avgMs=0
[09:56:14.811] R990:25.37 star: usb: polls=8217 (821.7/s) T=1 F=8216 term=0 lock=0 nodata=8216 bytes=2 lines=1 avgMs=0
[09:56:14.917] R990:25.48 star: ===================================

[09:56:15.291] R990:25.85 star: gps: invalid RMC data: $GPRMC,235943.100,V,,,,,,,050180,,,N,V*30
[09:56:15.394] R990:25.95 star: gps: seeking location (0 sats seen)
[09:56:15.486] R990:26.05 star: gps: line took 269ms to process
[09:56:15.582] R990:26.14 star: GPS STATS: lines=5 sent=0 avgMs=53
[09:56:15.676] R990:26.24 star: gps: processing latency 458ms
[09:56:15.900] R990:26.46 star: modem: powered on
[09:56:16.036] R990:26.58 star: gps: processing latency 107ms
[09:56:16.167] R990:26.73 star: modem: >> AT+QSCLK=0
[09:56:16.316] R990:26.87 star: gps: processing latency 113ms
[09:56:16.700] R990:27.24 star: modem: processing latency 2046ms
[09:56:16.808] R990:27.37 star: gps: processing latency 133ms
[09:56:16.998] R990:27.56 star: modem: processing latency 237ms
[09:56:17.128] R990:27.66 star: gps: processing latency 107ms
[09:56:17.290] R990:27.85 star: modem: processing latency 211ms
[09:56:17.394] R990:27.96 star: gps: processing latency 107ms
[09:56:17.496] R990:28.06 star: modem: << F1: 0000 0000
[09:56:17.606] R990:28.17 star: gps: processing latency 107ms
[09:56:17.716] R990:28.28 star: modem: processing latency 240ms
[09:56:17.822] R990:28.38 star: gps: processing latency 107ms
[09:56:17.931] R990:28.49 star: modem: processing latency 133ms
[09:56:18.035] R990:28.60 star: gps: processing latency 107ms
[09:56:18.145] R990:28.70 star: modem: << V0: 0000 0000 [0001]
[09:56:18.249] R990:28.81 star: gps: processing latency 107ms
[09:56:18.359] R990:28.92 star: modem: processing latency 240ms
[09:56:18.466] R990:29.03 star: gps: processing latency 107ms
[09:56:18.654] R990:29.21 star: modem: processing latency 211ms
[09:56:18.757] R990:29.32 star: gps: processing latency 107ms
[09:56:18.858] R990:29.42 star: modem: << 00: 0006 000C
[09:56:18.968] R990:29.53 star: gps: processing latency 107ms
[09:56:19.077] R990:29.64 star: modem: processing latency 240ms
[09:56:19.183] R990:29.75 star: gps: processing latency 107ms
[09:56:19.374] R990:29.93 star: modem: processing latency 211ms
[09:56:19.476] R990:30.04 star: modem: processing latency 211ms
[09:56:19.594] R990:30.15 star: gps: line took 214ms to process
[09:56:19.677] R990:30.22 star: gps: processing latency 321ms
[09:56:19.880] R990:30.44 star: gps: processing latency 107ms
[09:56:19.989] R990:30.55 star: modem: processing latency 509ms
[09:56:20.198] R990:30.76 star: modem: processing latency 104ms
[09:56:20.302] R990:30.86 star: gps: processing latency 107ms
[09:56:20.409] R990:30.97 star: modem: << U0: 0000 0001 [0000]
[09:56:20.517] R990:31.08 star: gps: processing latency 107ms
[09:56:20.625] R990:31.19 star: modem: processing latency 240ms
[09:56:20.729] R990:31.29 star: gps: processing latency 107ms
[09:56:20.919] R990:31.48 star: modem: processing latency 211ms
[09:56:21.046] R990:31.58 star: gps: processing latency 107ms
[09:56:21.146] R990:31.71 star: modem: << T0: 0000 00B4
[09:56:21.284] R990:31.82 star: gps: processing latency 107ms
[09:56:21.371] R990:31.93 star: modem: processing latency 240ms
[09:56:21.590] R990:32.14 star: modem: processing latency 104ms
[09:56:21.732] R990:32.25 star: gps: processing latency 107ms
[09:56:21.833] R990:32.35 star: modem: << Leaving the BROM
[09:56:21.932] R990:32.46 star: gps: line took 107ms to process
[09:56:22.007] R990:32.57 star: gps: processing latency 214ms
[09:56:22.116] R990:32.68 star: modem: processing latency 321ms
[09:56:22.331] R990:32.89 star: modem: processing latency 104ms
[09:56:22.432] R990:32.99 star: gps: processing latency 107ms
[09:56:22.623] R990:33.18 star: modem: processing latency 211ms
[09:56:22.724] R990:33.29 star: gps: processing latency 107ms
[09:56:22.915] R990:33.47 star: modem: processing latency 104ms
[09:56:22.999] R990:33.56 star: modem: << RDY
[09:56:24.233] R990:34.79 star: 
[09:56:24.259] === SERIAL STATS (last 10 sec) ===
[09:56:24.446] R990:35.00 star: notecard: polls=125929 (12592.9/s) T=0 F=125929 term=0 lock=0 nodata=125929 bytes=0 lines=0 avgMs=0
[09:56:24.708] R990:35.25 star: gps: polls=127503 (12750.3/s) T=97392 F=30111 term=49908 lock=47335 nodata=30111 bytes=2482 lines=75 avgMs=0
[09:56:24.928] R990:35.49 star: modem: polls=128366 (12836.6/s) T=128366 F=0 term=90108 lock=38236 nodata=0 bytes=133 lines=19 avgMs=0
[09:56:25.128] R990:35.69 star: usb: polls=128071 (12807.1/s) T=0 F=128071 term=0 lock=0 nodata=128071 bytes=0 lines=0 avgMs=0
[09:56:25.226] R990:35.79 star: ===================================

[09:56:25.317] R990:35.88 star: gps: processing latency 1116ms
[09:56:25.495] R990:36.03 star: gps: invalid RMC data: $GPRMC,235952.099,V,,,,,,,050180,,,N,V*31
[09:56:25.568] R990:36.12 star: gps: line took 161ms to process
[09:56:25.649] R990:36.21 star: modem: >> ATE0
[09:56:25.784] R990:36.35 star: GPS STATS: lines=72 sent=0 avgMs=6
[09:56:25.908] R990:36.47 star: gps: processing latency 509ms
[09:56:26.363] R990:36.92 star: modem: processing latency 3340ms
[09:56:26.555] S990:37.05 ntn: waiting for wireless service {wait-service} {connecting} powering up {ntn-connecting}{ntn-power}{ntn-gps}{ntn-unknown-location}
[09:56:26.586] R990:37.09 star: modem: << +CFUN: 1
[09:56:26.679] R990:37.24 star: modem: processing latency 159ms
[09:56:26.833] R990:37.35 star: gps: processing latency 107ms
[09:56:26.894] R990:37.45 star: modem: processing latency 133ms
[09:56:26.998] R990:37.56 star: gps: processing latency 107ms
[09:56:27.099] R990:37.66 star: modem: << +CPIN: READY
[09:56:27.214] R990:37.77 star: gps: processing latency 107ms
[09:56:27.322] R990:37.88 star: modem: processing latency 214ms
[09:56:27.417] R990:37.98 star: modem: << AT+QSCLK=0
[09:56:27.511] R990:38.07 star: modem: << AT+QSCLK=0
[09:56:27.617] R990:38.18 star: modem: processing latency 161ms
[09:56:27.723] R990:38.28 star: gps: processing latency 107ms
[09:56:27.855] R990:38.37 star: modem: << OK
[09:56:27.926] R990:38.49 star: modem: >> AT+QSCLK=0
[09:56:28.042] R990:38.60 star: modem: >> AT+QSCLK=0
[09:56:28.230] R990:38.79 star: modem: processing latency 425ms
[09:56:28.335] R990:38.89 star: gps: processing latency 107ms
[09:56:28.444] R990:39.00 star: modem: processing latency 133ms
[09:56:28.548] R990:39.11 star: gps: processing latency 107ms
[09:56:28.649] R990:39.21 star: modem: << +QGNSSINFO: 1
[09:56:28.762] R990:39.32 star: gps: processing latency 107ms
[09:56:28.872] R990:39.43 star: modem: processing latency 214ms
[09:56:28.962] R990:39.52 star: modem: << ATE0
[09:56:29.060] R990:39.62 star: modem: << ATE0
[09:56:29.183] R990:39.73 star: modem: processing latency 161ms
[09:56:29.272] R990:39.83 star: gps: processing latency 107ms
[09:56:29.364] R990:39.92 star: modem: << OK
[09:56:29.514] R990:40.07 star: gps: processing latency 107ms
[09:56:29.633] R990:40.17 star: modem: >> AT+QCCID
[09:56:29.728] R990:40.29 star: gps: processing latency 107ms
[09:56:29.942] R990:40.50 star: modem: processing latency 584ms
[09:56:30.064] R990:40.60 star: gps: processing latency 107ms
[09:56:30.155] R990:40.71 star: modem: processing latency 133ms
[09:56:30.353] R990:40.91 << {"err":"cannot interpret JSON: ':' expected near '\"ntn.trace\"' {io}"}

[09:56:30.503] R990:41.03 star: gps: line took 214ms to process
[09:56:30.580] R990:41.14 star: modem: processing latency 214ms
[09:56:30.675] R990:41.23 star: notecard: processing latency 146ms
[09:56:30.898] R990:41.46 star: {"err":"missing 'req' field specifying the type of request {io}"}
[09:56:30.980] R990:41.54 star: gps: processing latency 856ms
[09:56:31.430] R990:41.99 star: modem: processing latency 765ms
[09:56:31.539] R990:42.09 star: gps: processing latency 107ms
[09:56:31.745] R990:42.24 star: modem: << +QCCID: 89901980100600018548
[09:56:31.927] R990:42.36 star: gps: processing latency 134ms
[09:56:32.064] R990:42.51 star: modem: >> AT+CGSN=1
[09:56:32.129] R990:42.68 star: gps: processing latency 107ms
[09:56:32.805] R990:43.36 star: gps: processing latency 214ms
[09:56:32.942] R990:43.50 star: modem: processing latency 1322ms
[09:56:32.998] S990:43.56 ntn: waiting for wireless service {wait-service} {connecting} powering up {ntn-connecting}{ntn-power}{ntn-gps}{ntn-unknown-location}
[09:56:33.120] R990:43.68 star: gps: processing latency 133ms
[09:56:33.229] R990:43.79 star: modem: processing latency 107ms
[09:56:33.316] R990:43.87 star: modem: << OK
[09:56:33.415] R990:43.97 star: modem: << OK
[09:56:34.177] R990:44.73 star: modem: processing latency 756ms
[09:56:34.281] R990:44.84 star: modem: processing latency 756ms
[09:56:34.390] R990:44.95 star: gps: : << +CGSN: 860952060042554
[09:56:34.504] R990:45.05 star: gps: line took 214ms to process
[09:56:34.602] R990:45.16 star: gps: processing latency 454ms
[09:56:34.752] R990:45.31 star: 
[09:56:34.779] === SERIAL STATS (last 10 sec) ===
[09:56:35.009] S990:45.57 ntn: waiting for wireless service {wait-service} {connecting} initializing modem {ntn-connecting}{ntn-power}{ntn-gps}{ntn-unknown-location}
[09:56:35.101] R990:45.66 star: modem: >> AT+CEREG=1
[09:56:35.204] R990:45.76 star: modem: >> AT+CEREG=1
[09:56:35.470] R990:46.03 star: gps: polls=115648 (11564.8/s) T=115648 F=0 term=68290 lock=47206 nodata=0 bytes=2991 lines=76 avgMs=0
[09:56:35.595] R990:46.15 star: modem: processing latency 1310ms
[09:56:35.703] R990:46.26 star: modem: << OK
[09:56:35.926] R990:46.48 star: modem: << OK
[09:56:36.033] R990:46.59 star: modem: >> AT+CSCON=1
[09:56:36.257] R990:46.81 star: usb: polls=118818 (11881.8/s) T=0 F=118818 term=0 lock=0 nodata=118818 bytes=0 lines=0 avgMs=0
[09:56:36.385] R990:46.94 star: ===================================

[09:56:36.477] R990:47.03 star: gps: processing latency 1792ms
[09:56:36.599] R990:47.14 star: modem: processing latency 857ms
[09:56:36.702] R990:47.26 star: modem: << OK
[09:56:36.794] R990:47.35 star: gps: processing latency 241ms
[09:56:36.894] R990:47.45 star: gps 0/2000 (1 overruns)
[09:56:37.178] R990:47.72 star: modem: >> AT+CRTDCP=1
[09:56:37.382] R990:47.94 star: modem: processing latency 691ms
[09:56:37.493] R990:48.05 star: modem: << OK
[09:56:38.765] R990:49.32 star: gps: invalid RMC data: $GPRMC,095638.088,V,,,,,,,070226,,,N,V*37
[09:56:38.847] R990:49.41 star: gps: line took 161ms to process
[09:56:38.926] R990:49.48 star: gps: processing latency 242ms
[09:56:40.120] R990:50.67 star: modem: >> AT+QLOCKF=0
[09:56:40.713] R990:51.27 star: modem: processing latency 3195ms
[09:56:40.822] R990:51.38 star: modem: << OK
[09:56:40.947] R990:51.50 star: gps: processing latency 107ms
[09:56:41.024] S990:51.57 ntn: waiting for wireless service {wait-service} {connecting} initializing modem {ntn-connecting}{ntn-power}{ntn-gps}{ntn-unknown-location}
[09:56:41.071] R990:51.63 star: modem: >> AT+QLOCKF=0
[09:56:41.196] R990:51.74 star: gps: processing latency 107ms
[09:56:41.437] R990:51.96 star: modem: processing latency 557ms
[09:56:41.504] R990:52.06 star: gps: processing latency 107ms
[09:56:41.665] R990:52.17 star: modem: processing latency 133ms
[09:56:41.721] R990:52.28 star: gps: processing latency 107ms
[09:56:41.808] R990:52.37 star: modem: << OK
[09:56:41.934] R990:52.49 star: gps: line took 107ms to process
[09:56:42.067] R990:52.60 star: gps: processing latency 240ms
[09:56:42.146] R990:52.70 star: gps: processing latency 240ms
[09:56:42.282] R990:52.81 star: gps: processing latency 133ms
[09:56:42.459] R990:53.00 star: modem: processing latency 639ms
[09:56:42.547] R990:53.10 star: gps: processing latency 107ms
[09:56:42.682] R990:53.21 star: modem: processing latency 107ms
[09:56:42.755] R990:53.31 star: modem: << +QGNSSINFO: 0
[09:56:42.840] R990:53.40 star: modem: << +QGNSSINFO: 0
[09:56:42.952] R990:53.51 star: modem: processing latency 161ms
[09:56:43.054] R990:53.61 star: gps: processing latency 107ms
[09:56:43.168] R990:53.73 star: modem: << OK
[09:56:43.295] R990:53.85 star: gps: processing latency 133ms
[09:56:43.401] R990:53.96 star: gps: processing latency 133ms
[09:56:43.509] R990:54.07 star: gps: processing latency 133ms
[09:56:43.698] R990:54.25 star: modem: processing latency 558ms
[09:56:43.800] R990:54.36 star: gps: processing latency 107ms
[09:56:43.910] R990:54.47 star: modem: processing latency 133ms
[09:56:44.016] R990:54.57 star: modem: processing latency 133ms
[09:56:44.233] R990:54.79 star: gps: line took 214ms to process
[09:56:44.332] R990:54.89 star: modem: >> AT+QCGDEFCONT?
[09:56:44.471] R990:55.00 star: gps: processing latency 428ms
[09:56:44.606] R990:55.16 star: modem: processing latency 613ms
[09:56:44.708] R990:55.27 star: gps: processing latency 107ms
[09:56:44.823] R990:55.37 star: modem: processing latency 107ms
[09:56:44.913] R990:55.46 star: modem: << OK
[09:56:45.029] R990:55.59 star: gps: processing latency 133ms
[09:56:45.261] R990:55.82 star: 
[09:56:45.287] === SERIAL STATS (last 10 sec) ===
[09:56:45.500] R990:56.05 star: notecard: polls=126532 (12653.2/s) T=0 F=126532 term=0 lock=0 nodata=126532 bytes=0 lines=0 avgMs=0
[09:56:45.826] R990:56.38 star: gps: polls=126537 (12653.7/s) T=74509 F=52028 term=48839 lock=25557 nodata=52028 bytes=2436 lines=57 avgMs=0
[09:56:46.276] R990:56.83 star: modem: polls=124841 (12484.1/s) T=124841 F=0 term=106506 lock=18303 nodata=0 bytes=59 lines=16 avgMs=0
[09:56:46.432] R990:56.95 star: modem: processing latency 1444ms
[09:56:46.528] S990:57.08 ntn: waiting for wireless service {wait-service} {connecting} initializing modem {ntn-connecting}{ntn-power}{ntn-gps}{ntn-unknown-location}
[09:56:46.615] R990:57.14 star: usb: polls=125376 (12537.6/s) T=0 F=125376 term=0 lock=0 nodata=125376 bytes=0 lines=0 avgMs=0
[09:56:46.692] R990:57.25 star: modem: processing latency 161ms
[09:56:46.819] R990:57.37 star: ===================================

[09:56:46.960] R990:57.51 star: modem: << +QCGDEFCONT: "Non-IP","blues.prod"
[09:56:47.084] R990:57.62 star: gps: processing latency 1927ms
[09:56:47.385] R990:57.93 star: gps: seeking location (0 sats seen)
[09:56:47.493] R990:58.05 star: modem: processing latency 107ms
[09:56:47.602] R990:58.16 star: gps: line took 215ms to process
[09:56:47.701] R990:58.24 star: modem: << OK
[09:56:47.842] R990:58.38 star: GPS STATS: lines=57 sent=0 avgMs=12
[09:56:47.936] R990:58.49 star: modem: >> AT+CPIN?
[09:56:48.055] R990:58.61 star: gps: processing latency 177ms
[09:56:48.237] R990:58.79 star: gps: invalid RMC data: $GPRMC,095647.086,V,,,,,,,070226,,,N,V*31
[09:56:48.350] R990:58.91 star: gps: line took 187ms to process
[09:56:48.458] R990:59.01 star: gps: processing latency 294ms
[09:56:48.591] R990:59.15 star: gps: processing latency 294ms
[09:56:48.725] R990:59.28 star: gps: processing latency 133ms
[09:56:48.861] R990:59.41 star: modem: processing latency 107ms
[09:56:48.969] R990:59.52 star: modem: << OK
[09:56:49.134] R990:59.68 star: gps: processing latency 133ms
[09:56:50.285] R991:00.84 star: modem: >> AT+QENG=0
[09:56:50.544] R991:01.09 star: modem: processing latency 1542ms
[09:56:51.036] S991:01.59 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (0/900 secs) {ntn-connecting}{ntn-power}{ntn-gps}{ntn-unknown-location}
[09:56:51.089] R991:01.64 star: modem: processing latency 450ms
[09:56:51.187] R991:01.74 star: modem: << +QGNSSINFO: 1
[09:56:51.306] R991:01.86 star: modem: processing latency 106ms
[09:56:51.481] R991:02.03 star: modem: << +CPIN: READY
[09:56:51.623] R991:02.18 star: modem: processing latency 185ms
[09:56:51.733] R991:02.29 star: modem: << OK
[09:56:51.859] R991:02.41 star: gps: processing latency 107ms
[09:56:51.980] R991:02.54 star: modem: >> AT+CEREG?
[09:56:52.101] R991:02.65 star: gps: processing latency 107ms
[09:56:52.335] R991:02.87 star: modem: processing latency 557ms
[09:56:52.416] R991:02.97 star: gps: processing latency 107ms
[09:56:52.525] R991:03.08 star: modem: processing latency 107ms
[09:56:52.658] R991:03.21 star: gps: processing latency 7,2,25,R991:03.38 star: modem: << +QENG: 0,228907,2,25,"266051",-137,-19,-118,-8,255,"3AB1",0,,3
[09:56:52.949] R991:03.48 star: gps: processing latency 161ms
[09:56:53.035] R991:03.59 star: modem: processing latency 401ms
[09:56:53.138] R991:03.69 star: gps: processing latency 107ms
[09:56:53.252] R991:03.80 star: modem: processing latency 107ms
[09:56:53.334] R991:03.89 star: modem: << OK
[09:56:53.442] R991:04.00 star: modem: << OK
[09:56:53.645] R991:04.20 star: gps: line took 52ms to process
[09:56:53.732] R991:04.28 star: gps: processing latency 133ms
[09:56:53.888] R991:04.44 star: gps: line took 26ms to process
[09:56:53.991] R991:04.55 star: gps: processing latency 133ms
[09:56:54.565] R991:05.11 star: modem: >> AT+QENG=0
[09:56:54.809] R991:05.36 star: modem: processing latency 1370ms
[09:56:54.942] R991:05.47 star: gps: processing latency 107ms
[09:56:55.008] R991:05.56 star: modem: << +CEREG: 1,2
[09:56:55.175] R991:05.71 star: modem: processing latency 159ms
[09:56:55.257] R991:05.81 star: gps: processing latency 107ms
[09:56:55.349] R991:05.90 star: modem: << OK
[09:56:55.471] R991:06.03 star: gps: processing latency 107ms
[09:56:55.616] R991:06.15 star: modem: >> AT+CEREG?
[09:56:55.726] R991:06.27 star: gps: processing latency 107ms
[09:56:55.888] R991:06.44 star: 
[09:56:55.914] === SERIAL STATS (last 10 sec) ===
[09:56:56.126] R991:06.68 star: notecard: polls=137611 (13761.1/s) T=0 F=137611 term=0 lock=0 nodata=137611 bytes=0 lines=0 avgMs=0
[09:56:56.549] S991:07.10 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (4/900 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:56:56.642] R991:07.19 star: gps: polls=137341 (13734.1/s) T=87972 F=49369 term=51039 lock=36786 nodata=49369 bytes=3946 lines=74 avgMs=0
[09:56:56.762] R991:07.31 star: modem: processing latency 1362ms
[09:56:57.203] R991:07.74 star: modem: << +QENG: 0,228907,2,25,"266051",-137,-19,-118,-8,255,"3AB1",0,,3
[09:56:57.370] R991:07.92 star: modem: processing latency 402ms
[09:56:57.466] R991:08.02 star: ===================================

[09:56:57.560] R991:08.11 star: gps: processing latency 1740ms
[09:56:57.670] R991:08.22 star: gps: processing latency 1740ms
[09:56:57.778] R991:08.31 star: GPS STATS: lines=74 sent=0 avgMs=3
[09:56:57.880] R991:08.44 star: modem: processing latency 107ms
[09:56:57.985] R991:08.54 star: gps: processing latency 322ms
[09:56:58.077] R991:08.63 star: modem: << OK
[09:56:58.196] R991:08.75 star: gps 0/2000 (3 overruns)
[09:56:58.337] R991:08.89 star: modem: processing latency 214ms
[09:56:58.809] R991:09.36 star: gps: line took 26ms to process
[09:56:58.887] R991:09.44 star: gps: processing latency 133ms
[09:56:59.022] R991:09.57 star: gps: line took 26ms to process
[09:56:59.100] R991:09.65 star: gps: processing latency 107ms
[09:56:59.488] R991:10.04 star: modem: >> AT+QENG=0
[09:56:59.753] R991:10.29 star: modem: processing latency 1289ms
[09:56:59.879] R991:10.43 star: modem: << +CEREG: 1,2
[09:57:00.107] R991:10.66 star: modem: processing latency 159ms
[09:57:00.209] R991:10.76 star: gps: processing latency 107ms
[09:57:00.318] R991:10.87 star: modem: processing latency 133ms
[09:57:00.425] R991:10.98 star: gps: processing latency 107ms
[09:57:00.515] R991:11.07 star: modem: << OK
[09:57:00.643] R991:11.19 star: gps: line took 107ms to process
[09:57:00.746] R991:11.30 star: gps: processing latency 240ms
[09:57:00.972] R991:11.51 star: gps: processing latency 133ms
[09:57:01.494] R991:12.04 star: modem: processing latency 984ms
[09:57:01.558] S991:12.11 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (9/900 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:01.765] R991:12.32 star: modem: << +QENG: 0,228907,2,25,"266051",-137,-19,-118,-8,255,"3AB1",0,,3
[09:57:01.883] R991:12.42 star: gps: processing latency 161ms
[09:57:02.000] R991:12.55 star: modem: processing latency 294ms
[09:57:02.100] R991:12.64 star: modem: << OK
[09:57:02.210] R991:12.76 star: modem: << OK
[09:57:02.867] R991:13.42 star: gps: line took 26ms to process
[09:57:02.987] R991:13.52 star: gps: processing latency 133ms
[09:57:03.104] R991:13.66 star: gps: line took 26ms to process
[09:57:03.184] R991:13.74 star: gps: processing latency 107ms
[09:57:03.282] R991:13.83 star: modem: >> AT+QENG=0
[09:57:03.407] R991:13.95 star: gps: processing latency 107ms
[09:57:03.614] R991:14.17 star: modem: processing latency 1425ms
[09:57:03.715] R991:14.27 star: gps: processing latency 107ms
[09:57:03.816] R991:14.37 star: modem: << +CEREG: 1,2
[09:57:03.960] R991:14.51 star: modem: processing latency 159ms
[09:57:04.062] R991:14.62 star: gps: processing latency 107ms
[09:57:04.183] R991:14.73 star: modem: processing latency 107ms
[09:57:04.258] R991:14.81 star: modem: << OK
[09:57:04.372] R991:14.93 star: modem: >> AT+CEREG?
[09:57:04.552] R991:15.10 star: modem: >> AT+CEREG?
[09:57:04.792] R991:15.31 star: modem: processing latency 476ms
[09:57:04.886] R991:15.44 star: gps: processing latency 133ms
[09:57:05.036] R991:15.57 star: modem: processing latency 107ms
[09:57:05.202] R991:15.73 star: gps: processing latency 133ms
[09:57:05.399] R991:15.95 star: modem: << +QENG: 0,228907,2,25,"266051",-136,-18,-118,-7,255,"3AB1",0,,3
[09:57:05.524] R991:16.08 star: gps: processing latency 187ms
[09:57:05.656] R991:16.19 star: modem: processing latency 479ms
[09:57:05.750] R991:16.30 star: modem: << OK
[09:57:05.881] R991:16.42 star: gps: processing latency 107ms
[09:57:06.021] R991:16.56 star: modem: processing latency 187ms
[09:57:06.127] R991:16.68 star: 
[09:57:06.156] === SERIAL STATS (last 10 sec) ===
[09:57:06.421] R991:16.97 star: notecard: polls=123701 (12370.1/s) T=0 F=123701 term=0 lock=0 nodata=123701 bytes=0 lines=0 avgMs=0
[09:57:06.665] R991:17.18 star: notecard: polls=123701 (12370.1/s) T=0 F=123701 term=0 lock=0 nodata=123701 bytes=0 lines=0 avgMs=0
[09:57:06.899] R991:17.43 star: gps: polls=122224 (12222.4/s) T=98123 F=24101 term=54828 lock=43170 nodata=24101 bytes=3708 lines=63 avgMs=0
[09:57:07.196] R991:17.75 star: modem: polls=123059 (12305.9/s) T=123059 F=0 term=83495 lock=39526 nodata=0 bytes=205 lines=19 avgMs=0
[09:57:07.421] R991:17.97 star: usb: polls=122636 (12263.6/s) T=0 F=122636 term=0 lock=0 nodata=122636 bytes=0 lines=0 avgMs=0
[09:57:07.563] R991:18.10 star: ===================================

[09:57:07.639] R991:18.19 star: gps: processing latency 1657ms
[09:57:07.928] R991:18.48 star: gps 0/2000 (4 overruns)
[09:57:08.045] R991:18.59 star: modem: processing latency 1927ms
[09:57:08.083] S991:18.62 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (13/900 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:08.151] R991:18.70 star: gps: line took 107ms to process
[09:57:08.247] R991:18.80 star: modem: << +CEREG: 1,2
[09:57:08.374] R991:18.93 star: modem: processing latency 133ms
[09:57:08.469] R991:19.02 star: gps: processing latency 751ms
[09:57:08.577] R991:19.13 star: gps: processing latency 751ms
[09:57:08.665] R991:19.21 star: modem: << OK
[09:57:08.770] R991:19.32 star: modem: << OK
[09:57:08.978] R991:19.53 star: gps: line took 26ms to process
[09:57:09.055] R991:19.61 star: gps: processing latency 133ms
[09:57:09.214] R991:19.77 star: gps: line took 26ms to process
[09:57:09.295] R991:19.85 star: gps: processing latency 133ms
[09:57:09.795] R991:20.33 star: modem: >> AT+QENG=0
[09:57:09.898] R991:20.45 star: gps: processing latency 107ms
[09:57:10.126] R991:20.66 star: modem: processing latency 1427ms
[09:57:10.228] R991:20.77 star: gps: processing latency 107ms
[09:57:10.325] R991:20.88 star: modem: processing latency 133ms
[09:57:10.460] R991:20.98 star: gps: processing latency 107ms
[09:57:10.555] R991:21.07 star: modem: << OK
[09:57:10.664] R991:21.20 star: gps: line took 107ms to process
[09:57:10.751] R991:21.30 star: gps: processing latency 240ms
[09:57:10.967] R991:21.52 star: gps: processing latency 133ms
[09:57:11.160] R991:21.70 star: modem: processing latency 639ms
[09:57:11.260] R991:21.81 star: gps: processing latency 107ms
[09:57:11.381] R991:21.92 star: modem: processing latency 133ms
[09:57:11.482] R991:22.02 star: gps: processing latency 107ms
[09:57:11.573] R991:22.12 star: modem: << +QGNSSINFO: 0
[09:57:11.745] R991:22.24 star: gps: processing latency 107ms
[09:57:11.804] R991:22.34 star: modem: processing latency 214ms
[09:57:12.040] R991:22.59 star: modem: << +QENG: 0,228907,2,25,"266051",-134,-16,-118,-5,255,"3AB1",0,,3
[09:57:12.141] R991:22.69 star: gps: processing latency 161ms
[09:57:12.599] S991:23.12 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (19/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:12.630] R991:23.14 star: gps: processing latency 215ms
[09:57:12.725] R991:23.28 star: modem: processing latency 746ms
[09:57:12.814] R991:23.36 star: modem: << OK
[09:57:12.937] R991:23.49 star: modem: << OK
[09:57:13.256] R991:23.80 star: gps: line took 26ms to process
[09:57:13.334] R991:23.88 star: gps: processing latency 133ms
[09:57:13.496] R991:24.04 star: gps: line took 26ms to process
[09:57:13.575] R991:24.12 star: gps: processing latency 133ms
[09:57:14.063] R991:24.61 star: modem: >> AT+QENG=0
[09:57:14.205] R991:24.75 star: gps: processing latency 107ms
[09:57:14.368] R991:24.92 star: modem: processing latency 1451ms
[09:57:14.470] R991:25.02 star: gps: processing latency 107ms
[09:57:14.593] R991:25.12 star: modem: << +CEREG: 1,2
[09:57:14.710] R991:25.26 star: modem: processing latency 159ms
[09:57:14.817] R991:25.37 star: gps: processing latency 107ms
[09:57:14.925] R991:25.48 star: modem: processing latency 133ms
[09:57:15.034] R991:25.58 star: gps: processing latency 107ms
[09:57:15.130] R991:25.67 star: modem: << OK
[09:57:15.275] R991:25.82 star: modem: >> AT+CEREG?
[09:57:15.369] R991:25.92 star: modem: >> AT+CEREG?
[09:57:15.486] R991:26.03 star: gps: processing latency 107ms
[09:57:15.700] R991:26.25 star: modem: processing latency 584ms
[09:57:15.801] R991:26.35 star: gps: processing latency 107ms
[09:57:15.916] R991:26.46 star: modem: processing latency 133ms
[09:57:16.029] R991:26.57 star: gps: processing latency 107ms
[09:57:16.126] R991:26.66 star: modem: << +CSCON: 1
[09:57:16.230] R991:26.78 star: gps: processing latency 107ms
[09:57:16.341] R991:26.89 star: modem: processing latency 214ms
[09:57:16.443] R991:26.99 star: gps: processing latency 107ms
[09:57:16.566] R991:27.12 star: 
[09:57:16.592] === SERIAL STATS (last 10 sec) ===
[09:57:16.708] R991:27.24 star: gps: line took 134ms to process
[09:57:16.791] R991:27.34 star: gps: processing latency 267ms
[09:57:17.006] R991:27.54 star: notecard: polls=114318 (11431.8/s) T=0 F=114318 term=0 lock=0 nodata=114318 bytes=0 lines=0 avgMs=0
[09:57:17.149] R991:27.66 star: gps: processing latency 241ms
[09:57:17.397] R991:27.92 star: gps: polls=112609 (11260.9/s) T=112609 F=0 term=64306 lock=48140 nodata=0 bytes=4764 lines=82 avgMs=0
[09:57:17.487] R991:28.04 star: gps: processing latency 241ms
[09:57:17.709] R991:28.24 star: modem: polls=113388 (11338.8/s) T=113388 F=0 term=82381 lock=30971 nodata=0 bytes=204 lines=18 avgMs=0
[09:57:17.823] R991:28.36 star: gps: processing latency 241ms
[09:57:17.912] R991:28.46 star: gps 0/2000 (5 overruns)
[09:57:18.104] R991:28.65 star: gps: processing latency 14/s) T=0 F=114294 term=0 lock=0 nodata=114294 bytes=0 lines=0 avgMs=0
[09:57:18.205] R991:28.75 star: ===================================

[09:57:18.296] R991:28.85 star: modem: processing latency 1849ms
[09:57:18.572] R991:29.12 star: modem: << +QENG: 0,228907,2,25,"266051",-136,-18,-118,-8,255,"3AB1",0,230,3
[09:57:18.672] R991:29.22 star: gps: processing latency 162ms
[09:57:18.844] R991:29.39 star: GPS STATS: lines=87 sent=0 avgMs=3
[09:57:18.939] R991:29.49 star: gps: processing latency 160ms
[09:57:19.287] R991:29.83 star: gps: processing latency 215ms
[09:57:19.397] R991:29.94 star: modem: processing latency 1017ms
[09:57:19.509] R991:30.06 star: modem: << OK
[09:57:19.601] S991:30.13 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (23/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:19.632] R991:30.16 star: modem: << OK
[09:57:19.741] R991:30.29 star: gps: processing latency 373ms
[09:57:19.901] R991:30.45 star: gps: line took 26ms to process
[09:57:19.979] R991:30.53 star: gps: processing latency 133ms
[09:57:20.883] R991:31.43 star: modem: >> AT+QENG=0
[09:57:21.026] R991:31.57 star: gps: processing latency 133ms
[09:57:21.346] R991:31.86 star: modem: processing latency 1708ms
[09:57:21.451] R991:31.99 star: gps: processing latency 133ms
[09:57:21.546] R991:32.09 star: modem: << +CEREG: 1,2
[09:57:21.664] R991:32.21 star: gps: processing latency 107ms
[09:57:21.804] R991:32.32 star: modem: processing latency 214ms
[09:57:21.874] R991:32.42 star: gps: processing latency 107ms
[09:57:22.039] R991:32.51 star: modem: << OK
[09:57:22.113] R991:32.66 star: modem: >> AT+CEREG?
[09:57:22.170] cy 107ms
[09:57:22.209] R991:32.76 star: modem: >> AT+CEREG?
[09:57:22.327] R991:32.88 star: gps: processing latency 107ms
[09:57:22.544] R991:33.09 star: modem: processing latency 584ms
[09:57:22.647] R991:33.19 star: gps: processing latency 107ms
[09:57:22.755] R991:33.30 star: modem: processing latency 133ms
[09:57:22.859] R991:33.41 star: gps: processing latency 107ms
[09:57:23.057] R991:33.60 star: modem: << +QENG: 0,228907,2,25,"266051",-135,-18,-118,-7,255,"3AB1",0,230,3
[09:57:23.157] R991:33.70 star: gps: processing latency 162ms
[09:57:23.264] R991:33.81 star: modem: processing latency 321ms
[09:57:23.369] R991:33.92 star: gps: processing latency 107ms
[09:57:23.477] R991:34.03 star: modem: processing latency 133ms
[09:57:23.582] R991:34.13 star: gps: processing latency 107ms
[09:57:23.670] R991:34.22 star: modem: << OK
[09:57:23.828] R991:34.37 star: gps: line took 107ms to process
[09:57:23.929] R991:34.48 star: gps: processing latency 266ms
[09:57:24.307] R991:34.85 star: gps: processing latency 241ms
[09:57:24.410] R991:34.96 star: gps: processing latency 241ms
[09:57:24.518] R991:35.07 star: gps: processing latency 133ms
[09:57:24.633] S991:35.14 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (30/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:24.662] R991:35.20 star: gps: line took 26ms to process
[09:57:24.757] R991:35.31 star: gps: processing latency 107ms
[09:57:24.894] R991:35.44 star: gps: line took 26ms to process
[09:57:24.997] R991:35.52 star: gps: processing latency 133ms
[09:57:25.133] R991:35.68 star: gps: line took 26ms to process
[09:57:25.212] R991:35.76 star: gps: processing latency 133ms
[09:57:25.622] R991:36.17 star: modem: >> AT+QENG=0
[09:57:25.737] R991:36.29 star: gps: processing latency 107ms
[09:57:25.958] R991:36.50 star: modem: processing latency 1457ms
[09:57:26.055] R991:36.60 star: gps: processing latency 107ms
[09:57:26.154] R991:36.70 star: modem: << +CEREG: 1,2
[09:57:26.320] R991:36.85 star: modem: processing latency 159ms
[09:57:26.402] R991:36.95 star: gps: processing latency 107ms
[09:57:26.539] R991:37.06 star: modem: processing latency 133ms
[09:57:26.616] R991:37.16 star: gps: processing latency 107ms
[09:57:26.707] R991:37.25 star: modem: << OK
[09:57:26.856] R991:37.40 star: gps: processing latency 107ms
[09:57:26.984] R991:37.50 star: modem: >> AT+CEREG?
[09:57:27.070] R991:37.62 star: gps: processing latency 107ms
[09:57:27.286] R991:37.83 star: modem: processing latency 584ms
[09:57:27.431] R991:37.94 star: gps: processing latency 107ms
[09:57:27.516] R991:38.05 star: modem: processing latency 133ms
[09:57:27.604] R991:38.15 star: modem: processing latency 133ms
[09:57:27.800] R991:38.34 star: modem: << +QENG: 0,228907,2,25,"266051",-135,-18,-118,-7,255,"3AB1",0,230,3
[09:57:27.989] R991:38.54 star: notecard: polls=120226 (12022.6/s) T=0 F=120226 term=0 lock=0 nodata=120226 bytes=0 lines=0 avgMs=0
[09:57:28.282] R991:38.66 star: modem: processing latency 536ms
[09:57:28.438] R991:38.87 star: gps: polls=120139 (12013.9/s) T=112873 F=7266 term=60781 lock=51939 nodata=7266 bytes=4503 lines=77 avgMs=0
[09:57:28.438] R991:38.98 star: modem: processing latency 188ms
[09:57:28.523] R991:39.07 star: modem: << OK
[09:57:28.772] R991:39.29 star: modem: polls=119993 (11999.3/s) T=119993 F=0 term=91414 lock=28543 nodata=0 bytes=198 lines=18 avgMs=0
[09:57:28.993] R991:39.52 star: usb: polls=119691 (11969.1/s) T=0 F=119691 term=0 lock=0 nodata=119691 bytes=0 lines=0 avgMs=0
[09:57:29.098] R991:39.64 star: ===================================

[09:57:29.200] R991:39.73 star: gps: processing latency 1716ms
[09:57:29.296] R991:39.84 star: gps  processing latency 1716ms
[09:57:29.391] R991:39.94 star: gps 0/2000 (6 overruns)
[09:57:29.573] R991:40.12 star: GPS STATS: lines=74 sent=0 avgMs=4
[09:57:29.665] R991:40.21 star: gps: processing latency 400ms
[09:57:30.121] R991:40.61 star: gps: processing latency 215ms
[09:57:30.151] S991:40.64 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (35/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:30.179] R991:40.72 star: modem: processing latency 1551ms
[09:57:30.270] R991:40.82 star: modem: << +CEREG: 1,2
[09:57:30.366] R991:40.91 star: modem: << +CEREG: 1,2
[09:57:30.523] R991:41.04 star: modem: processing latency 161ms
[09:57:30.582] R991:41.13 star: modem: << OK
[09:57:30.709] R991:41.26 star: gps: line took 107ms to process
[09:57:30.817] R991:41.36 star: gps: processing latency 347ms
[09:57:30.973] R991:41.50 star: gps: line took 26ms to process
[09:57:31.043] R991:41.58 star: gps: processing latency 107ms
[09:57:31.856] R991:42.35 star: modem: >> AT+QENG=0
[09:57:31.918] R991:42.47 star: gps: processing latency 107ms
[09:57:32.138] R991:42.68 star: modem: processing latency 1528ms
[09:57:32.236] R991:42.78 star: gps: processing latency 107ms
[09:57:32.349] R991:42.89 star: modem: processing latency 133ms
[09:57:32.465] R991:43.00 star: gps: processing latency 107ms
[09:57:32.540] R991:43.09 star: modem: << OK
[09:57:32.666] R991:43.21 star: gps: line took 107ms to process
[09:57:32.774] R991:43.32 star: gps: processing latency 240ms
[09:57:32.870] R991:43.41 star: modem: >> AT+CEREG?
[09:57:32.985] R991:43.53 star: gps: processing latency 133ms
[09:57:33.174] R991:43.72 star: modem: processing latency 639ms
[09:57:33.276] R991:43.82 star: gps: processing latency 107ms
[09:57:33.501] R991:44.04 star: modem: << +QENG: 0,228907,2,25,"266051",-135,-18,-118,-7,255,"3AB1",0,230,3
[09:57:33.601] R991:44.15 star: gps: processing latency 162ms
[09:57:33.709] R991:44.25 star: modem: processing latency 321ms
[09:57:33.795] R991:44.34 star: modem: << OK
[09:57:33.902] R991:44.45 star: modem: << OK
[09:57:34.810] R991:45.35 star: gps: line took 26ms to process
[09:57:34.912] R991:45.46 star: gps: processing latency 133ms
[09:57:35.050] R991:45.59 star: gps: line took 26ms to process
[09:57:35.174] R991:45.69 star: modem: >> AT+QENG=0
[09:57:35.260] R991:45.81 star: gps: processing latency 107ms
[09:57:35.777] R991:46.31 star: gps: processing latency 215ms
[09:57:35.879] R991:46.42 star: modem: processing latency 1952ms
[09:57:36.026] R991:46.57 star: modem: << +CEREG: 1,2
[09:57:36.104] S991:46.65 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (44/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:36.249] R991:46.79 star: modem: processing latency 159ms
[09:57:36.363] R991:46.90 star: gps: processing latency 107ms
[09:57:36.460] R991:47.01 star: modem: processing latency 133ms
[09:57:36.627] R991:47.14 star: modem: << OK
[09:57:36.750] R991:47.30 star: modem: >> AT+CEREG?
[09:57:36.873] R991:47.42 star: modem: >> AT+CEREG?
[09:57:37.023] R991:47.56 star: gps: processing latency 159ms
[09:57:37.284] R991:47.83 star: modem: processing latency 661ms
[09:57:37.463] R991:47.98 star: gps: processing latency 133ms
[09:57:37.627] R991:48.15 star: modem: processing latency 159ms
[09:57:37.731] R991:48.25 star: gps: processing latency 107ms
[09:57:37.902] R991:48.44 star: modem: << +QENG: 0,228907,2,25,"266051",-135,-18,-118,-7,255,"3AB1",0,230,3
[09:57:38.124] R991:48.65 star: modem: processing latency 214ms
[09:57:38.211] R991:48.76 star: gps: processing latency 107ms
[09:57:38.346] R991:48.87 star: modem: processing latency 133ms
[09:57:38.426] R991:48.97 star: gps: processing latency 107ms
[09:57:38.518] R991:49.06 star: modem: << OK
[09:57:38.666] R991:49.21 star: gps: processing latency 107ms
[09:57:38.775] R991:49.32 star: modem: processing latency 133ms
[09:57:38.896] R991:49.44 star: 
[09:57:38.923] === SERIAL STATS (last 10 sec) ===
[09:57:39.169] R991:49.70 star: notecard: polls=126069 (12606.9/s) T=0 F=126069 term=0 lock=0 nodata=126069 bytes=0 lines=0 avgMs=0
[09:57:39.411] R991:49.95 star: gps: polls=125227 (12522.7/s) T=122978 F=2249 term=71570 lock=51225 nodata=2249 bytes=5397 lines=92 avgMs=0
[09:57:39.646] R991:50.19 star: modem: polls=123203 (12320.3/s) T=123203 F=0 term=89487 lock=33680 nodata=0 bytes=198 lines=18 avgMs=0
[09:57:39.846] R991:50.39 star: usb: polls=123099 (12309.9/s) T=0 F=123099 term=0 lock=0 nodata=123099 bytes=0 lines=0 avgMs=0
[09:57:39.959] R991:50.49 star: ===================================

[09:57:40.034] R991:50.58 star: gps: processing latency 1286ms
[09:57:40.136] R991:50.68 star: modem: >> AT+QENG=0
[09:57:40.243] R991:50.79 star: gps 0/2000 (7 overruns)
[09:57:40.414] R991:50.96 star: modem: processing latency 1552ms
[09:57:40.518] R991:51.06 star: gps: line took 133ms to process
[09:57:40.611] R991:51.16 star: modem: << +CEREG: 1,2
[09:57:40.785] R991:51.29 star: GPS STATS: lines=92 sent=0 avgMs=4
[09:57:40.862] R991:51.41 star: gps: processing latency 747ms
[09:57:41.289] R991:51.83 star: modem: processing latency 693ms
[09:57:41.396] R991:51.91 star: modem: processing latency 693msR991:52.03 star: modem: << OK
[09:57:41.634] R991:52.15 star: gps: processing latency 133ms
[09:57:41.682] S991:52.16 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (49/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:41.731] R991:52.25 star: gps: processing latenR991:52.37 star: gps: line took 133ms to process
[09:57:41.956] R991:52.50 star: gps: processing latency 214ms
[09:57:42.064] R991:52.61 star: modem: processing latency 613ms
[09:57:42.173] R991:52.71 star: gps: processing latency 107ms
[09:57:42.278] R991:52.82 star: modem: processing latency 107ms
[09:57:42.500] R991:53.04 star: modem: << +QENG: 0,228907,2,25,"266051",-135,-18,-117,-7,255,"3AB1",0,230,3
[09:57:42.572] R991:53.12 star: gps: processing latency 162ms
[09:57:42.709] R991:53.25 star: modem: processing latency 269ms
[09:57:42.811] R991:53.36 star: modem: << OK
[09:57:42.930] R991:53.44 star: modem: << OK
[09:57:43.027] R991:53.57 star: gps: processing latency 107ms
[09:57:43.163] R991:53.70 star: modem: processing latency 294ms
[09:57:43.264] R991:53.81 star: gps: processing latency 107ms
[09:57:44.402] R991:54.95 star: modem: >> AT+QENG=0
[09:57:44.656] R991:55.20 star: modem: processing latency 1303ms
[09:57:44.764] R991:55.29 star: modem: << +CEREG: 1,2
[09:57:44.864] R991:55.41 star: modem: processing latency 106ms
[09:57:44.972] R991:55.52 star: gps: line took 107ms to process
[09:57:45.061] R991:55.60 star: modem: << OK
[09:57:45.184] R991:55.73 star: gps: processing latency 321ms
[09:57:45.306] R991:55.85 star: modem: >> AT+CEREG?
[09:57:45.423] R991:55.97 star: gps: processing latency 107ms
[09:57:45.640] R991:56.18 star: modem: processing latency 584ms
[09:57:45.742] R991:56.29 star: gps: processing latency 107ms
[09:57:45.880] R991:56.42 star: modem: processing latency 107ms
[09:57:45.982] R991:56.53 star: gps: processing latency 133ms
[09:57:46.157] R991:56.70 star: gps: processing latency 133ms
[09:57:46.276] R991:56.82 star: gps: processing latency 214ms
[09:57:46.623] S991:57.16 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (54/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:46.707] R991:57.25 star: modem: processing latency 747ms
[09:57:46.784] R991:57.33 star: modem: processing latency 747msR991:57.44 star: modem: << OK
[09:57:47.047] R991:57.57 star: gps: processing latency 133ms
[09:57:47.134] R991:57.67 star: gps: processing latency 133ms
[09:57:47.298] R991:57.84 star: gps: line took 133ms to process
[09:57:47.373] R991:57.91 star: gps: processing latency 266ms
[09:57:47.509] R991:58.05 star: modem: >> AT+QGNSSINFO=41.372880,2.166560,0,0,0
[09:57:47.643] R991:58.18 star: gps: line took 134ms to process
[09:57:47.748] R991:58.29 star: gps: processing latency 293ms
[09:57:47.883] R991:58.42 star: modem: processing latency 666ms
[09:57:47.987] R991:58.53 star: gps: processing latency 107ms
[09:57:48.108] R991:58.65 star: modem: << +CEREG: 1,2
[09:57:48.198] R991:58.74 star: gps: processing latency 107ms
[09:57:48.308] R991:58.85 star: modem: processing latency 214ms
[09:57:48.412] R991:58.96 star: gps: processing latency 107ms
[09:57:48.499] R991:59.04 star: modem: << OK
[09:57:48.959] R991:59.50 star: 
[09:57:48.985] === SERIAL STATS (last 10 sec) ===
[09:57:49.169] R991:59.71 star: notecard: polls=110715 (11071.5/s) T=0 F=110715 term=0 lock=0 nodata=110715 bytes=0 lines=0 avgMs=0
[09:57:49.417] R991:59.96 star: gps: polls=110435 (11043.5/s) T=99347 F=11088 term=63587 lock=35601 nodata=11088 bytes=4687 lines=80 avgMs=0
[09:57:49.652] R992:00.19 star: modem: polls=110398 (11039.8/s) T=110398 F=0 term=81813 lock=28547 nodata=0 bytes=200 lines=19 avgMs=0
[09:57:49.757] R992:00.30 star: modem: >> AT+QENG=0
[09:57:49.984] R992:00.53 star: usb: polls=110377 (11037.7/s) T=0 F=110377 term=0 lock=0 nodata=110377 bytes=0 lines=0 avgMs=0
[09:57:50.107] R992:00.65 star: ===================================

[09:57:50.202] R992:00.74 star: gps: processing latency 1286ms
[09:57:50.308] R992:00.85 star: modem: processing latency 1812ms
[09:57:50.412] R992:00.96 star: gps: processing latency 107ms
[09:57:50.510] R992:01.05 star: modem: << +CEREG: 1
[09:57:50.658] R992:01.20 star: godem: processing latency 132ms
[09:57:50.750] R992:01.29 star: GPS STATS: lines=81 sent=0 avgMs=7
[09:57:50.845] R992:01.38 star: gps: processing latency 348ms
[09:57:50.949] R992:01.49 star: gps 0/2000 (8 overruns)
[09:57:51.057] R992:01.57 star: gps 0/2000 (8 overruns)
[09:57:51.118] R992:01.66 star: modem: << OK
[09:57:51.248] R992:01.79 star: gps: line took 107ms to process
[09:57:51.404] R992:01.92 star: gps: processing latency 373ms
[09:57:51.608] R992:02.13 star: gps: processing latency 107ms
[09:57:52.149] S992:02.67 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (59/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[09:57:52.176] R992:02.69 star: gps: processing latency 215ms
[09:57:52.256] R992:02.80 star: modem: processing latency 1143ms
[09:57:52.382] R992:02.91 star: gps: line took 107ms to process
[09:57:52.466] R992:03.01 star: gps: processing latency 240ms
[09:57:52.599] R992:03.14 star: gps: processing latency 240ms
[09:57:52.744] R992:03.28 star: gps: processing latency 133ms
[09:57:52.867] R992:03.41 star: modem: processing latency 425ms
[09:57:53.008] R992:03.55 star: modem: << OK
[09:57:53.179] R992:03.70 star: modem: << OK
[09:57:53.639] S992:04.18 ntn: {connected} waiting for satellite network (59/120 secs){ntn-power}{ntn-gps}{ntn-connected}
[09:57:53.666] ### 2026-02-07T09:57:44Z 16h 32m 4s 4.75V (4.76V avg over 15m) ###
[09:57:53.692] S992:04.21 ntn: adding downlink request note into packet (6/254)
[09:57:53.692] S992:04.21 packet: sending 4 bytes (encoded as 6 bytes on-air)
[09:57:53.735] R992:04.26 star: gps: processing latency 241ms
[09:57:53.851] R992:04.39 star: modem: processing latency 689ms
[09:57:53.879] S992:04.41 ntn: sent 4-byte packet containing 1 notes
[09:57:53.969] R992:04.51 star: notecard: processing latency 107ms
[09:57:54.087] R992:04.63 star: gps: processing latency 267ms
[09:57:54.440] R992:04.98 star: modem: processing latency 214ms
[09:57:54.756] R992:05.30 star: gps: processing latency 452ms
[09:57:55.001] R992:05.53 star: gps: line took 52ms to process
[09:57:55.099] R992:05.64 star: gps: processing latency 185ms
[09:57:55.368] R992:05.91 star: gps: processing latency 161ms
[09:57:55.517] R992:06.06 star: modem: >> AT+CSODCP=1,6,"00FA00000000"
[09:57:55.632] R992:06.17 star: gps: processing latency 134ms
[09:57:55.859] R992:06.39 star: modem: processing latency 111ms
[09:57:55.950] R992:06.49 star: gps: processing latency 107ms
[09:57:56.062] R992:06.60 star: modem: processing latency 133ms
[09:57:56.121] R992:06.66 star: modem: << OK
[09:57:56.239] R992:06.78 star: modem: << OK
[09:57:56.386] R992:06.92 star: gps: processing latency 295ms
[09:57:56.497] S992:06.97 sync: project: opening notehub session using product UID product:com.blues.ray_b
[09:57:56.497] S992:06.97 sync: notehub: opened IP connection {notehub-connected}
[09:57:56.497] S992:06.98 sync: sync triggered by explicit sync request; NTN
[09:57:56.497] S992:06.98 sync: work: begin (anything pending) {sync-begin}
[09:57:56.530] S992:07.04 sync: work: completed (nothing was pending) {sync-end}
[09:57:56.530] S992:07.05 sync: advancing last sync time from 17:38:29Z to 09:57:47Z
[09:57:56.678] R992:07.22 star: gps: processing latency 161ms
[09:57:57.102] R992:07.63 star: gps: line took 52ms to process
[09:57:57.227] R992:07.77 star: gps: processing latency 185ms
[09:57:59.127] R992:09.67 star: 
[09:57:59.154] === SERIAL STATS (last 10 sec) ===
[09:57:59.427] R992:09.97 star: notecard: polls=133852 (13385.2/s) T=2290 F=131562 term=810 lock=1477 nodata=131562 bytes=70 lines=3 avgMs=0
[09:57:59.784] R992:10.28 star: gps: polls=136400 (13640.0/s) T=128590 F=7810 term=82532 lock=45952 nodata=7810 bytes=3111 lines=54 avgMs=0
[09:58:00.040] R992:10.58 star: modem: polls=138413 (13841.3/s) T=59279 F=79134 term=39551 lock=19701 nodata=79134 bytes=120 lines=13 avgMs=0
[09:58:00.336] R992:10.87 star: usb: polls=139901 (13990.1/s) T=0 F=139901 term=0 lock=0 nodata=139901 bytes=0 lines=0 avgMs=0
[09:58:00.436] R992:10.97 star: ===================================

[09:58:00.551] R992:11.09 star: gps: processing latency 1491ms
[09:58:00.699] R992:11.24 star: GPS STATS: lines=53 sent=0 avgMs=6
[09:58:00.818] R992:11.36 star: gps: processing latency 186ms
[09:58:01.266] R992:11.80 star: gps: line took 52ms to process
[09:58:01.368] R992:11.91 star: gps: processing latency 211ms
[09:58:01.613] R992:12.15 star: gps: line took 52ms to process
[09:58:01.720] R992:12.26 star: gps: processing latency 211ms

notecard_usb_1.txt

[10:06:24.657] R1000:35.09 ntn: skylo:901980060001854
[10:06:37.890] R1000:48.32 trace is now on
[10:06:37.945] R1000:48.33 firmware size:921227 max:1015808 (94581 remaining)
[10:06:38.952] R1000:49.38 ntn: skylo:901980060001854
[10:06:39.717] R1000:50.15 trace options:
[10:06:39.744]   requested: +star
[10:06:39.744]         now: comm,sync,mem,gps,star
[10:09:50.694] {}
[10:09:50.755] S1004:01.14 sync: connect requested by explicit sync request (PACKET)
[10:09:50.826] S1004:01.22 connect: 4.75V 26.31C before comms power-on (2026-02-07T10:09:41Z UTC)
[10:09:51.855] R1004:02.24 star: 
[10:09:51.881] === SERIAL STATS (last 10 sec) ===
[10:09:51.881] S1004:02.26 ntn: idle {disconnected} {ntn-idle}{ntn-unknown-location}
[10:09:52.001] R1004:02.39 star: notecard: polls=90 (9.0/s) T=16 F=74 term=6 lock=0 nodata=68 bytes=199 lines=14 avgMs=0
[10:09:52.138] R1004:02.53 star: gps: polls=91 (9.1/s) T=0 F=91 term=0 lock=0 nodata=91 bytes=0 lines=0 avgMs=0
[10:09:52.276] R1004:02.67 star: modem: polls=92 (9.2/s) T=0 F=92 term=0 lock=0 nodata=92 bytes=0 lines=0 avgMs=0
[10:09:52.413] R1004:02.80 star: usb: polls=94 (9.4/s) T=0 F=94 term=0 lock=0 nodata=94 bytes=0 lines=0 avgMs=0
[10:09:52.506] R1004:02.89 star: ===================================

[10:09:52.624] R1004:02.99 star: notecard: processing latency 660ms
[10:09:52.879] S1004:03.27 ntn: waiting for wireless service {wait-service} {connecting} powering up {ntn-connecting}{ntn-unknown-location}
[10:09:53.523] R1004:03.88 star: gps: powered on
[10:09:55.880] R1004:06.27 star: gps: invalid RMC data: $GPRMC,235943.100,V,,,,,,,050180,,,N,V*30
[10:09:55.970] R1004:06.36 star: gps: seeking location (0 sats seen)
[10:09:56.047] R1004:06.44 star: gps: line took 214ms to process
[10:09:56.138] R1004:06.53 star: GPS STATS: lines=5 sent=0 avgMs=42
[10:09:56.212] R1004:06.60 star: gps: processing latency 380ms
[10:09:56.541] R1004:06.93 star: modem: powered on
[10:09:56.604] R1004:06.99 star: modem: >> AT+QSCLK=0
[10:09:56.798] R1004:07.19 star: modem: processing latency 1540ms
[10:09:58.833] R1004:09.22 star: modem: << F1: 0000 0000
[10:09:59.885] R1004:10.27 star: gps: invalid RMC data: $GPRMC,235947.099,V,,,,,,,050180,,,N,V*35
[10:09:59.958] R1004:10.35 star: gps: line took 123ms to process
[10:10:00.052] R1004:10.42 star: gps: processing latency 200ms
[10:10:00.109] R1004:10.50 star: gps: processing latency 200ms
[10:10:00.913] R1004:11.30 star: modem: << V0: 0000 0000 [0001]
[10:10:01.857] R1004:12.24 star: 
[10:10:01.884] === SERIAL STATS (last 10 sec) ===
[10:10:02.006] R1004:12.39 star: notecard: polls=241 (24.1/s) T=0 F=241 term=0 lock=0 nodata=241 bytes=0 lines=0 avgMs=0
[10:10:02.153] R1004:12.54 star: gps: polls=240 (24.0/s) T=119 F=121 term=68 lock=2 nodata=51 bytes=1926 lines=60 avgMs=0
[10:10:02.298] R1004:12.68 star: modem: polls=238 (23.8/s) T=6 F=232 term=194 lock=3 nodata=35 bytes=39 lines=6 avgMs=0
[10:10:02.439] R1004:12.82 star: usb: polls=237 (23.7/s) T=0 F=237 term=0 lock=0 nodata=237 bytes=0 lines=0 avgMs=0
[10:10:02.526] R1004:12.92 star: ===================================

[10:10:02.603] R1004:12.99 star: gps: processing latency 764ms
[10:10:02.898] R1004:13.27 star: modem: << 00: 0006 000C
[10:10:03.882] R1004:14.27 star: gps: invalid RMC data: $GPRMC,235951.099,V,,,,,,,050180,,,N,V*32
[10:10:03.969] R1004:14.35 star: gps: line took 122ms to process
[10:10:04.111] R1004:14.50 star: gps: processing latency 197ms
[10:10:04.929] R1004:15.32 star: modem: << 01: 0000 0000
[10:10:05.873] R1004:16.25 star: GPS STATS: lines=81 sent=0 avgMs=3
[10:10:06.557] R1004:16.94 star: modem: << U0: 0000 0001 [0000]
[10:10:06.739] R1004:17.12 star: modem: processing latency 178ms
[10:10:07.529] R1004:17.91 star: modem: << T0: 0000 00B4
[10:10:08.709] R1004:19.09 star: gps: invalid RMC data: $GPRMC,101008.087,V,,,,,,,070226,,,N,V*31
[10:10:08.803] R1004:19.17 star: gps: line took 122ms to process
[10:10:08.860] R1004:19.25 star: gps: line took 122ms to process
[10:10:08.934] R1004:19.32 star: gps: processing latency 197ms
[10:10:09.005] R1004:19.39 star: modem: << Leaving the BROM
[10:10:10.825] R1004:21.21 star: modem: << RDY
[10:10:12.389] S1004:22.78 ntn: waiting for wireless service {wait-service} {connecting} powering up {ntn-connecting}{ntn-power}{ntn-gps}{ntn-unknown-location}
[10:10:13.472] R1004:23.86 star: modem: >> ATE0
[10:10:13.589] R1004:23.95 star: modem: processing latency 2719ms
[10:10:13.667] R1004:24.04 star: 
[10:10:13.667] === SERIAL STATS (last 10 sec) ===
[10:10:13.824] R1004:24.19 star: notecard: polls=306 (30.6/s) T=0 F=306 term=0 lock=0 nodata=306 bytes=0 lines=0 avgMs=0
[10:10:13.948] R1004:24.33 star: gps: polls=310 (31.0/s) T=181 F=129 term=100 lock=0 nodata=29 bytes=3787 lines=89 avgMs=0
[10:10:14.099] R1004:24.48 star: modem: polls=310 (31.0/s) T=19 F=291 term=291 lock=0 nodata=0 bytes=105 lines=15 avgMs=0
[10:10:14.240] R1004:24.62 star: usb: polls=310 (31.0/s) T=0 F=310 term=0 lock=0 nodata=310 bytes=0 lines=0 avgMs=0
[10:10:14.331] R1004:24.71 star: ===================================

[10:10:14.410] R1004:24.79 star: modem: processing latency 680ms
[10:10:14.469] R1004:24.86 star: modem: << +CFUN: 1
[10:10:14.544] R1004:24.93 star: modem: << +CFUN: 1
[10:10:14.722] R1004:25.10 star: modem: << +CPIN: READY
[10:10:14.779] R1004:25.17 star: modem: << AT+QSCLK=0
[10:10:15.697] R1004:26.08 star: modem: << OK
[10:10:15.761] R1004:26.15 star: modem: >> AT+QSCLK=0
[10:10:15.853] R1004:26.24 star: modem: processing latency 134ms
[10:10:16.710] R1004:27.09 star: modem: << +QGNSSINFO: 1
[10:10:16.800] R1004:27.18 star: GPS STATS: lines=71 sent=0 avgMs=1
[10:10:16.874] R1004:27.26 star: modem: processing latency 158ms
[10:10:16.934] R1004:27.32 star: modem: << ATE0
[10:10:17.398] S1004:27.78 ntn: waiting for wireless service {wait-service} {connecting} powering up {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:17.741] R1004:28.09 star: modem: << OK
[10:10:17.802] R1004:28.16 star: gps 0/2000 (1 overruns)
[10:10:17.877] R1004:28.26 star: modem: >> AT+QGNSSINFO=41.372908,2.166555,0,0,0
[10:10:17.969] R1004:28.36 star: modem: processing latency 243ms
[10:10:18.697] R1004:29.08 star: modem: << OK
[10:10:18.757] R1004:29.14 star: modem: >> AT+QCCID
[10:10:18.850] R1004:29.23 star: modem: processing latency 132ms
[10:10:19.698] R1004:30.08 star: modem: << OK
[10:10:21.333] R1004:31.71 star: modem: processing latency 614ms
[10:10:21.401] R1004:31.78 star: modem: << +QGNSSINFO: 0
[10:10:22.257] R1004:32.64 star: modem: processing latency 540ms
[10:10:23.117] R1004:33.50 star: modem: processing latency 398ms
[10:10:23.206] R1004:33.59 star: modem: << +QCCID: 89901980100600018548
[10:10:23.272] R1004:33.66 star: modem: >> AT+CGSN=1
[10:10:23.496] R1004:33.88 star: modem: processing latency 133ms
[10:10:23.730] R1004:34.11 star: 
[10:10:23.757] === SERIAL STATS (last 10 sec) ===
[10:10:23.878] R1004:34.26 star: notecard: polls=244 (24.4/s) T=0 F=244 term=0 lock=0 nodata=244 bytes=0 lines=0 avgMs=0
[10:10:23.906] S1004:34.29 ntn: waiting for wireless service {wait-service} {connecting} powering up {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:24.046] R1004:34.41 star: gps: polls=244 (24.4/s) T=122 F=122 term=94 lock=0 nodata=28 bytes=3339 lines=60 avgMs=0
[10:10:24.169] R1004:34.55 star: modem: polls=244 (24.4/s) T=38 F=206 term=203 lock=3 nodata=0 bytes=128 lines=20 avgMs=0
[10:10:24.312] R1004:34.70 star: usb: polls=244 (24.4/s) T=0 F=244 term=0 lock=0 nodata=244 bytes=0 lines=0 avgMs=0
[10:10:24.432] R1004:34.79 star: ===================================

[10:10:24.487] R1004:34.87 star: modem: processing latency 759ms
[10:10:24.538] R1004:34.92 star: modem: << OK
[10:10:25.397] R1004:35.78 star: modem: processing latency 671ms
[10:10:26.253] R1004:36.64 star: modem: processing latency 524ms
[10:10:26.328] R1004:36.71 star: modem: << +CGSN: 860952060042554
[10:10:26.536] R1004:36.92 star: modem: >> AT+CEREG=1
[10:10:26.700] R1004:37.08 star: modem: << OK
[10:10:26.763] R1004:37.15 star: modem: >> AT+CSCON=1
[10:10:26.856] R1004:37.24 star: GPS STATS: lines=83 sent=0 avgMs=0
[10:10:26.913] S1004:37.30 ntn: waiting for wireless service {wait-service} {connecting} initializing modem {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:26.941] R1004:37.32 star: modem: processing latency 217ms
[10:10:27.697] R1004:38.08 star: modem: << OK
[10:10:27.777] R1004:38.15 star: modem: >> AT+CRTDCP=1
[10:10:27.851] R1004:38.23 star: modem: processing latency 133ms
[10:10:28.698] R1004:39.08 star: modem: << OK
[10:10:31.407] R1004:41.79 star: modem: >> AT+QLOCKF=0
[10:10:31.642] R1004:42.02 star: modem: processing latency 2918ms
[10:10:31.698] R1004:42.08 star: modem: << OK
[10:10:31.765] R1004:42.15 star: modem: >> AT+QLOCKF=0
[10:10:31.857] R1004:42.24 star: modem: processing latency 135ms
[10:10:31.920] S1004:42.30 ntn: waiting for wireless service {wait-service} {connecting} initializing modem {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:32.695] R1004:43.08 star: modem: << OK
[10:10:32.798] R1004:43.18 star: modem: >> AT+QCGDEFCONT="Non-IP","blues.prod"
[10:10:32.894] R1004:43.27 star: modem: processing latency 176ms
[10:10:33.698] R1004:44.08 star: modem: << OK
[10:10:33.763] R1004:44.14 star: modem: >> AT+QBAND=0
[10:10:33.889] R1004:44.24 star: 
[10:10:33.920] === SERIAL STATS (last 10 sec) ===
[10:10:34.010] R1004:44.39 star: notecard: polls=348 (34.8/s) T=4 F=344 term=4 lock=0 nodata=340 bytes=4 lines=2 avgMs=0
[10:10:34.162] R1004:44.54 star: gps: polls=348 (34.8/s) T=188 F=160 term=130 lock=0 nodata=30 bytes=5100 lines=92 avgMs=0
[10:10:34.325] R1004:44.69 star: modem: polls=348 (34.8/s) T=30 F=318 term=315 lock=3 nodata=0 bytes=64 lines=15 avgMs=0
[10:10:34.477] R1004:44.83 star: usb: polls=348 (34.8/s) T=0 F=348 term=0 lock=0 nodata=348 bytes=0 lines=0 avgMs=0
[10:10:34.537] R1004:44.92 star: ===================================

[10:10:34.614] R1004:45.00 star: gps: processing latency 766ms
[10:10:34.729] R1004:45.07 star: gps: processing latency 766ms
[10:10:35.725] R1004:46.08 star: modem: << OK
[10:10:35.782] R1004:46.15 star: modem: >> AT+QCGDEFCONT?
[10:10:35.854] R1004:46.24 star: modem: processing latency 137ms
[10:10:36.697] R1004:47.08 star: modem: << OK
[10:10:36.761] R1004:47.14 star: modem: >> AT+CFUN=1
[10:10:36.828] R1004:47.21 star: modem: >> AT+CFUN=1
[10:10:36.918] R1004:47.30 star: modem: processing latency 199ms
[10:10:36.945] S1004:47.31 ntn: waiting for wireless service {wait-service} {connecting} initializing modem {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:37.747] R1004:48.13 star: modem: << +QCGDEFCONT: "Non-IP","blues.prod"
[10:10:37.833] R1004:48.22 star: GPS STATS: lines=67 sent=0 avgMs=0
[10:10:37.913] R1004:48.30 star: modem: processing latency 144ms
[10:10:38.709] R1004:49.08 star: modem: << OK
[10:10:38.798] R1004:49.18 star: modem: >> AT+QGNSSINFO=41.372883,2.166522,0,0,0
[10:10:38.895] R1004:49.27 star: modem: processing latency 176ms
[10:10:39.715] R1004:50.08 star: modem: << OK
[10:10:39.771] R1004:50.14 star: modem: >> AT+CPIN?
[10:10:39.847] R1004:50.23 star: modem: processing latency 129ms
[10:10:40.706] R1004:51.08 star: modem: << OK
[10:10:41.812] R1004:52.19 star: modem: >> AT+QENG=0
[10:10:42.077] R1004:52.46 star: modem: processing latency 1349ms
[10:10:42.441] S1004:52.82 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (0/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:42.712] R1004:53.09 star: modem: << +CPIN: READY
[10:10:43.731] R1004:54.09 star: modem: << OK
[10:10:43.784] R1004:54.16 star: modem: >> AT+CEREG?
[10:10:43.867] R1004:54.25 star: modem: processing latency 133ms
[10:10:43.987] R1004:54.37 star: 
[10:10:44.014] === SERIAL STATS (last 10 sec) ===
[10:10:44.138] R1004:54.51 star: notecard: polls=233 (23.3/s) T=0 F=233 term=0 lock=0 nodata=233 bytes=0 lines=0 avgMs=0
[10:10:44.282] R1004:54.66 star: gps: polls=233 (23.3/s) T=118 F=115 term=99 lock=0 nodata=16 bytes=3345 lines=59 avgMs=0
[10:10:44.431] R1004:54.81 star: modem: polls=233 (23.3/s) T=32 F=201 term=186 lock=15 nodata=0 bytes=90 lines=16 avgMs=0
[10:10:44.571] R1004:54.95 star: usb: polls=233 (23.3/s) T=0 F=233 term=0 lock=0 nodata=233 bytes=0 lines=0 avgMs=0
[10:10:44.662] R1004:55.04 star: ===================================

[10:10:44.800] R1004:55.18 star: modem: << +QENG: 0,228907,2,25,"266051",-137,-19,-118,-9,255,"3AB1",0,230,3
[10:10:45.706] R1004:56.08 star: modem: << OK
[10:10:46.818] R1004:57.19 star: modem: >> AT+QENG=0
[10:10:47.081] R1004:57.46 star: modem: processing latency 1261ms
[10:10:47.146] R1004:57.53 star: modem: << +CEREG: 1,2
[10:10:47.448] S1004:57.83 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (5/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:47.712] R1004:58.09 star: modem: << OK
[10:10:47.774] R1004:58.15 star: modem: >> AT+CEREG?
[10:10:47.863] R1004:58.24 star: GPS STATS: lines=103 sent=0 avgMs=0
[10:10:47.957] R1004:58.33 star: modem: processing latency 223ms
[10:10:48.790] R1004:59.17 star: modem: << +QENG: 0,228907,2,25,"266051",-136,-19,-118,-7,255,"3AB1",0,230,3
[10:10:48.874] R1004:59.24 star: gps: processing latency 136ms
[10:10:48.963] R1004:59.32 star: modem: processing latency 212ms
[10:10:49.712] R1005:00.09 star: modem: << OK
[10:10:50.859] R1005:01.21 star: modem: >> AT+QENG=0
[10:10:50.932] R1005:01.31 << {"err":"JSON object expected: e\",\"text\":\"\n=== SERIAL STATS (last... {io}"}

[10:10:50.987] R1005:01.36 << {"err":"JSON object expected: } {io}"}

[10:10:51.084] R1005:01.46 << {"err":"JSON object expected: < +QENG: 0,228907,2,25,\\"266051\\",-... {io}"}

[10:10:51.112] R1005:01.47 star: {"err":"missing 'req' field specifying the type of request {io}"}
[10:10:51.182] R1005:01.56 star: notecard: processing latency 114ms
[10:10:51.389] R1005:01.76 star: {"err":"missing 'req' field specifying the type of request {io}"}
[10:10:51.741] R1005:02.12 star: {"err":"JSON object expected {io}"}
[10:10:51.861] R1005:02.24 star: modem: >> AT+CEREG?
[10:10:52.129] R1005:02.50 star: gps: processing latency 175ms
[10:10:52.204] R1005:02.58 star: modem: processing latency 483ms
[10:10:52.461] S1005:02.84 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (9/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:52.784] R1005:03.16 star: modem: << +QENG: 0,228907,2,25,"266051",-137,-19,-118,-9,255,"3AB1",0,230,3
[10:10:52.880] R1005:03.23 star: gps: processing latency 137ms
[10:10:52.940] R1005:03.31 star: modem: processing latency 213ms
[10:10:53.700] R1005:04.08 star: modem: << OK
[10:10:54.779] R1005:05.12 star: 
[10:10:54.859] === SERIAL STATS (last 10 sec) ===
[10:10:54.859] R1005:05.19 star: modem: >> AT+QENG=0
[10:10:54.965] R1005:05.34 star: notecard: polls=459 (45.9/s) T=6 F=453 term=10 lock=0 nodata=443 bytes=200 lines=3 avgMs=0
[10:10:55.142] R1005:05.49 star: gps: polls=460 (46.0/s) T=262 F=198 term=172 lock=1 nodata=25 bytes=7163 lines=130 avgMs=0
[10:10:55.265] R1005:05.63 star: modem: polls=460 (46.0/s) T=42 F=418 term=416 lock=2 nodata=0 bytes=280 lines=21 avgMs=0
[10:10:55.334] R1005:05.71 star: modem: processing latency 1521ms
[10:10:55.401] R1005:05.78 star: modem: << +CEREG: 1,2
[10:10:55.466] R1005:05.84 star: modem: << +CEREG: 1,2
[10:10:55.559] R1005:05.94 star: ===================================

[10:10:55.638] R1005:06.01 star: gps: processing latency 905ms
[10:10:55.710] R1005:06.09 star: modem: << OK
[10:10:55.775] R1005:06.15 star: modem: >> AT+CEREG?
[10:10:55.888] R1005:06.24 star: modem: processing latency 130ms
[10:10:56.787] R1005:07.16 star: modem: << +QENG: 0,228907,2,25,"266051",-136,-19,-118,-8,255,"3AB1",0,230,3
[10:10:57.469] S1005:07.84 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (12/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[10:10:57.701] R1005:08.08 star: modem: << OK
[10:10:57.803] R1005:08.18 star: modem: >> AT+QGNSSINFO=41.372887,2.166523,0,0,0
[10:10:57.899] R1005:08.27 star: GPS STATS: lines=91 sent=0 avgMs=0
[10:10:57.986] R1005:08.36 star: modem: processing latency 160ms
[10:10:58.050] R1005:08.43 star: modem: << +CEREG: 1,2
[10:10:58.705] R1005:09.08 star: modem: << OK
[10:10:59.811] R1005:10.19 star: modem: >> AT+QENG=0
[10:10:59.905] R1005:10.28 star: modem: processing latency 1174ms
[10:11:00.715] R1005:11.09 star: modem: << OK
[10:11:00.778] R1005:11.15 star: modem: >> AT+CEREG?
[10:11:00.877] R1005:11.25 star: modem: processing latency 133ms
[10:11:01.018] R1005:11.39 star: modem: << +QENG: 0,228907,2,25,"266051",-135,-19,-117,-8,255,"3AB1",0,230,3
[10:11:01.099] R1005:11.47 star: gps: processing latency 136ms
[10:11:01.167] R1005:11.54 star: modem: processing latency 213ms
[10:11:01.708] R1005:12.08 star: modem: << OK
[10:11:02.812] R1005:13.19 star: modem: >> AT+QENG=0
[10:11:03.080] R1005:13.45 star: gps: processing latency 172ms
[10:11:03.157] R1005:13.53 star: modem: processing latency 1311ms
[10:11:03.237] R1005:13.59 star: modem: << +CEREG: 1,2
[10:11:03.480] S1005:13.85 ntn: waiting for wireless service {wait-service} {connecting} waiting for satellite network (21/120 secs) {ntn-connecting}{ntn-power}{ntn-gps}
[10:11:03.707] R1005:14.08 star: modem: << OK
[10:11:03.790] R1005:14.14 star: modem: >> AT+CEREG?
[10:11:03.858] R1005:14.24 star: modem: processing latency 132ms
[10:11:04.722] R1005:15.10 star: modem: << +CEREG: 1
[10:11:04.815] R1005:15.19 star: 
[10:11:04.869] === SERIAL STATS (last 10 sec) ===
[10:11:04.959] R1005:15.34 star: notecard: polls=354 (35.4/s) T=0 F=354 term=0 lock=0 nodata=354 bytes=0 lines=0 avgMs=0
[10:11:05.036] R1005:15.41 star: modem: processing latency 156ms
[10:11:05.187] R1005:15.56 star: gps: polls=354 (35.4/s) T=200 F=154 term=145 lock=1 nodata=8 bytes=5504 lines=99 avgMs=0
[10:11:05.335] R1005:15.71 star: modem: polls=354 (35.4/s) T=46 F=308 term=300 lock=8 nodata=0 bytes=219 lines=23 avgMs=0
[10:11:05.474] R1005:15.85 star: usb: polls=354 (35.4/s) T=0 F=354 term=0 lock=0 nodata=354 bytes=0 lines=0 avgMs=0
[10:11:05.566] R1005:15.94 star: ===================================

[10:11:05.639] R1005:16.02 star: gps: processing latency 795ms
[10:11:05.797] R1005:16.17 star: modem: << +QENG: 0,228907,2,25,"266051",-136,-19,-117,-8,255,"3AB1",0,230,3
[10:11:05.865] R1005:16.24 star: gps: processing latency 136ms
[10:11:05.942] R1005:16.32 star: modem: processing latency 212ms
[10:11:06.701] R1005:17.08 star: modem: << OK
[10:11:06.953] R1005:17.33 star: modem: processing latency 118ms
[10:11:07.006] S1005:17.36 ntn: {connected} waiting for satellite network (21/120 secs){ntn-power}{ntn-gps}{ntn-connected}
[10:11:07.031] R1005:17.39 star: modem: << +CEREG: 1,1
[10:11:07.092] R1005:17.47 star: modem: << OK
[10:11:07.146] S1005:17.52 ntn: adding downlink request note into packet (6/254)
[10:11:07.178] S1005:17.53 packet: sending 4 bytes (encoded as 6 bytes on-air)
[10:11:07.347] S1005:17.72 ntn: sent 4-byte packet containing 1 notes
[10:11:07.401] R1005:17.76 star: notecard: processing latency 137ms
[10:11:07.723] R1005:18.10 star: gps 0/2000 (3 overruns)
[10:11:07.958] R1005:18.33 star: modem: >> AT+CSODCP=1,6,"00FA00000000"
[10:11:08.033] R1005:18.41 star: gps: processing latency 189ms
[10:11:08.146] R1005:18.50 star: GPS STATS: lines=94 sent=0 avgMs=0
[10:11:08.211] R1005:18.59 star: modem: processing latency 165ms
[10:11:08.714] R1005:19.09 star: modem: << OK
[10:11:08.788] R1005:19.16 star: gps: line took 59ms to process
[10:11:08.863] R1005:19.24 star: gps: processing latency 134ms
[10:11:09.903] S1005:20.28 sync: project: opening notehub session using product UID product:com.blues.ray_b
[10:11:09.932] S1005:20.28 sync: notehub: opened IP connection {notehub-connected}
[10:11:09.932] S1005:20.28 sync: sync triggered by explicit sync request; NTN
[10:11:09.932] S1005:20.29 sync: work: begin (anything pending) {sync-begin}
[10:11:09.975] S1005:20.35 sync: work: completed (nothing was pending) {sync-end}
[10:11:10.002] S1005:20.36 sync: advancing last sync time from 09:57:47Z to 10:11:00Z
[10:11:15.863] R1005:26.23 star: 
[10:11:15.889] === SERIAL STATS (last 10 sec) ===
[10:11:16.011] R1005:26.38 star: notecard: polls=368 (36.8/s) T=7 F=361 term=6 lock=2 nodata=353 bytes=120 lines=7 avgMs=0
[10:11:16.160] R1005:26.53 star: gps: polls=368 (36.8/s) T=200 F=168 term=120 lock=3 nodata=45 bytes=5556 lines=98 avgMs=0
[10:11:16.308] R1005:26.68 star: modem: polls=368 (36.8/s) T=19 F=349 term=21 lock=0 nodata=328 bytes=101 lines=9 avgMs=0
[10:11:16.458] R1005:26.82 star: usb: polls=368 (36.8/s) T=0 F=368 term=0 lock=0 nodata=368 bytes=0 lines=0 avgMs=0
[10:11:16.538] R1005:26.91 star: ===================================

[10:11:16.632] R1005:27.00 star: notecard: processing latency 804ms
[10:11:16.715] R1005:27.08 star: gps: processing latency 905ms
[10:11:18.849] R1005:29.20 star: GPS STATS: lines=119 sent=0 avgMs=0
[10:11:21.766] R1005:32.14 star: modem: << +CSCON: 0
[10:11:21.919] R1005:32.29 star: modem: << +QGNSSINFO: 1
[10:11:22.017] R1005:32.39 star: modem: >> AT+QGNSSINFO=41.372887,2.166523,0,0,0
[10:11:22.245] R1005:32.61 star: modem: processing latency 138ms
[10:11:22.316] R1005:32.68 star: modem: << +QGNSSINFO: 0
[10:11:22.800] R1005:33.17 star: modem: << +CEREG: 2