Hello everyone,
I have been going through guide 5 attempting to transmit data to the sofar API. The first time I tried to do this I kept getting the message on the Spotter serial console “Message not sent. 32” followed by the message “Waiting 0.10 seconds before retrying.”
After some time I started over with the code outlined in guide 5 and got a single message to send. However, after reading the code on the spotter console I notice that the spotter was attempting to send the message multiple times and the same message of “Message not sent. 32” and “Waiting 0.10 seconds before retrying.” was occurring. I am worried that something might be wrong as it looks like the spotter was not sending all the messages it was trying to. The console says it sent more than one message, but I only received one message. All messages look to be printing on the sd card. Additionally I got a few unknown errors from the console.
My main questions are:
Should I be concerned with the message 32?
Using the code form the devkit how often can you expect a message? It looks like the spotter wants to send a message when the report stats are changed via the loop, but I could be misunderstanding the code.
What is the error magnet lost?
Are there any other errors I should be concerned with?
I have included the output from the console, I have erased the hum and temp data to shorten it.
315380t [ERR] [INFO] GpsErrorState changed from NO_SIGNAL to OK
315380t [SYS] [WARNING] Updating RTC from GPS time.
2024-07-25T00:11:01.000Z [SYS] [WARNING] Rotating logs.
2024-07-25T00:11:01.027Z [MS] [INFO] Added message(id: 0 len: 145) to queue MS_Q_LEGACY: (1)!
Message: DC 66 A1 98 15 DA 01 B0 02 05 90 DD 3E 95 85 33 C4 03 B6 67 5D 40 19 A5 C9 12 C3 20 80 3B 00 17 A1 40 3C 00 0B B9 94 60 F1 B5 89 62 71 00 00 0C 37 2D 0D 2C 83 06 18 F3 48 03 A0 74 89 8C 64 C7 96 64 B0 01 E1 F4 24 50 78 01 F4 01 DB E8 00 00 00 00 00 00 00 02 FF 3F 2A A3 7F 5E CA 40 DF 56 01 90 C7 0C 45 40 00 C1 81 D8 1F 40 1D 80 E8 1E 03 84 40 12 C2 45 40 40 00 38 48 22 13 94 E1 08 48 9C E5 29 D5 90 9C 63 18 4E 10 84 25 28 CA 8B 3E
2024-07-25T00:11:01.031Z [MFLT] [INFO] Sending memfault message!
Current bit offset: 134
Rounded bit offset: 136
New bit offset: 688
2024-07-25T00:11:01.031Z [MS] [INFO] Added message(id: 1 len: 86) to queue MS_Q_LEGACY: (2)!
Message: E4 66 A1 98 15 DA 01 B0 02 05 90 DD 3E 95 85 33 C4 08 02 A7 02 02 03 01 0A 6E 73 70 6F 74 74 65 72 5F 62 6D 2D 64 62 67 09 66 76 32 2E 39 2E 30 06 6E 73 75 6E 66 6C 6F 77 65 72 5F 76 33 2E 33 0B 46 5F 75 A8 A3 79 02 04 A3 01 07 04 1A 0C 00 06 03 05 00 FA B9
2024-07-25T00:11:01.035Z [MFLT] [INFO] Message 1 added to queue.
2024-07-25T00:11:01.035Z [MS] [INFO] Added message(id: 2 len: 36) to queue MS_Q_LEGACY: (3)!
Message: DD 66 A1 98 15 DA 01 B0 02 05 90 DD 3E 95 85 33 C4 03 B6 67 5D 33 50 CC 0A 80 02 68 0C 40 00 01 00 04 04 41
2024-07-25T00:11:01.035Z [ERR] [INFO] OrchErrorState changed from OK to SCHEDULE_SAMPLE_ALIGNMENT
2024-07-25T00:11:01.039Z [ERR] [INFO] Cellular SignalErrorState changed from N/A to CONNECTING
2024-07-25T00:11:01.039Z [MS] [DEBUG] Sending legacy messages to Notecard.
2024-07-25T00:11:01.058Z [MS] [DEBUG] Notecard is 82.000000 pct full.
2024-07-25T00:11:01.058Z [MS] [INFO] Setting GPS coordinates lat:27.716047, lon: -97.328285
2024-07-25T00:11:01.328Z [MS] [DEBUG] Attempting to Sync.
2024-07-25T00:11:01.351Z [MS] [DEBUG] Waiting for TX
2024-07-25T00:11:06.402Z [MS] [DEBUG] Notecard is 82.000000 pct full.
2024-07-25T00:11:06.417Z [MS] [DEBUG] Notecard is 82.000000 pct full.
2024-07-25T00:11:06.417Z [ERR] [INFO] CellularErrorState changed from OK to CELL_MEMORY_FULL
2024-07-25T00:11:06.417Z [ERR] [INFO] Cellular SignalErrorState changed from CONNECTING to NO_CONNECTION
2024-07-25T00:11:06.421Z [MS] [INFO] Messages not sent. Will try again later.
2024-07-25T00:11:06.421Z [MS] [DEBUG] Requeueing unexpired message 0 to queue(2)
2024-07-25T00:11:06.421Z [MS] [WARNING] Have not transmitted message over cellular in 120 minutes.
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:11:11.875Z [MS] [INFO] Added message(id: 3 len: 49) to queue MS_Q_LEGACY: (4)!
Message: DE 66 A1 98 1E DA 01 B0 02 05 A0 DD 3E 95 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 57 B8 C0 41 A2 86 2C 3D 0F 00 82 EF 75 42 3D 39 18 3F
2024-07-25T00:11:11.875Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:11:11.875Z [BM_TX] [DEBUG] Message:
0f 00 57 b8 c0 41 a2 86
2c 3d 0f 00 82 ef 75 42
3d 39 18 3f
2024-07-25T00:11:14.195Z [MS] [INFO] Notecard confirmed in idle mode and in low power
2024-07-25T00:11:14.199Z [MS] [INFO] Notecard disabled
2024-07-25T00:11:14.199Z [MS] [INFO] Entering Transmit Iridium State, powering down notecard.
2024-07-25T00:11:14.496Z [ORC] [INFO] Iridium modem enabled
2024-07-25T00:11:14.496Z [MS] [INFO] Trying to send message 0. Time remaining: 180000ms
2024-07-25T00:11:14.500Z [MS] [INFO] Attempting to send message(145)
2024-07-25T00:11:14.500Z [IRI] [INFO] Attempting to send 145 bytes.
2024-07-25T00:11:14.500Z [IRI] [INFO] Attempting to write buffer[145]
2024-07-25T00:11:14.605Z [IRI] [INFO] Waiting for network. Time remaining: 180.89 seconds
2024-07-25T00:11:15.753Z [GPS] [INFO] Max CNO changed from -1 to 49
2024-07-25T00:11:20.269Z [IRI] [INFO] Attempting to transmit. Time remaining: 175.23 seconds
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
Magnet lost
!!!
2024-07-25T00:11:31.687Z [MS] [INFO] Message sent successfully!
2024-07-25T00:11:31.687Z [ORC] [INFO] Message 0 transmitted successfully!
2024-07-25T00:11:31.687Z [MS] [INFO] Trying to send message 1. Time remaining: 180000ms
2024-07-25T00:11:31.687Z [MS] [INFO] Attempting to send message(86)
2024-07-25T00:11:31.691Z [IRI] [INFO] Attempting to send 86 bytes.
2024-07-25T00:11:31.691Z [IRI] [INFO] Attempting to write buffer[86]
2024-07-25T00:11:31.765Z [IRI] [INFO] Waiting for network. Time remaining: 180.92 seconds
2024-07-25T00:11:31.765Z [IRI] [INFO] Attempting to transmit. Time remaining: 180.92 seconds
2024-07-25T00:11:40.820Z [IRI] [INFO] Message sent successfully!!!
2024-07-25T00:11:40.820Z [MS] [INFO] Message sent successfully!
2024-07-25T00:11:40.820Z [MS] [INFO] Trying to send message 2. Time remaining: 180000ms
2024-07-25T00:11:40.824Z [MS] [INFO] Attempting to send message(36)
2024-07-25T00:11:40.824Z [IRI] [INFO] Attempting to send 36 bytes.
2024-07-25T00:11:40.824Z [IRI] [INFO] Attempting to write buffer[36]
2024-07-25T00:11:40.871Z [IRI] [INFO] Waiting for network. Time remaining: 180.95 seconds
2024-07-25T00:11:40.871Z [IRI] [INFO] Attempting to transmit. Time remaining: 180.95 seconds
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:11:41.804Z [MS] [INFO] Added message(id: 4 len: 49) to queue MS_Q_LEGACY: (2)!
Message: DE 66 A1 98 3C D9 FE B0 02 05 D0 DD 3E 95 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 49 DE C1 41 63 93 3D 3D 0F 00 9F 8F 6E 42 B2 29 FC 3E
2024-07-25T00:11:41.804Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:11:41.804Z [BM_TX] [DEBUG] Message:
0f 00 49 de c1 41 63 93
3d 3d 0f 00 9f 8f 6e 42
b2 29 fc 3e
2024-07-25T00:12:05.746Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:12:05.746Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:12:05.843Z [IRI] [INFO] Attempting to transmit. Time remaining: 165.76 seconds
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:12:11.765Z [MS] [INFO] Added message(id: 5 len: 49) to queue MS_Q_LEGACY: (2)!
Message: DE 66 A1 98 5A DA 01 B0 02 09 E8 DD 3E 9D 85 33 C0 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 A5 1A C3 41 AC 92 3B 3D 0F 00 8F 49 69 42 60 B3 AC 3E
2024-07-25T00:12:11.769Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:12:11.769Z [BM_TX] [DEBUG] Message:
0f 00 a5 1a c3 41 ac 92
3b 3d 0f 00 8f 49 69 42
60 b3 ac 3e
2024-07-25T00:12:36.714Z [BRIDGE_SYS] [INFO] Sample enabled 0
Sample Duration: 300 s
Sample Interval: 12000 s
Subsample enabled: 0
Subsample Duration: 30 s
Subsample Interval: 60 s
Alignment Interval: 300 s
2024-07-25T00:12:36.718Z [BRIDGE_SYS] [INFO] Bridge State Init Complete
2024-07-25T00:12:38.414Z [IRI] [INFO] Message sent successfully!!!
2024-07-25T00:12:38.414Z [MS] [INFO] Message sent successfully!
2024-07-25T00:12:38.414Z [MS] [INFO] Trying to send message 4. Time remaining: 180000ms
2024-07-25T00:12:38.414Z [MS] [INFO] Attempting to send message(49)
2024-07-25T00:12:38.414Z [IRI] [INFO] Attempting to send 49 bytes.
2024-07-25T00:12:38.414Z [IRI] [INFO] Attempting to write buffer[49]
2024-07-25T00:12:38.468Z [IRI] [INFO] Waiting for network. Time remaining: 180.94 seconds
2024-07-25T00:12:38.468Z [IRI] [INFO] Attempting to transmit. Time remaining: 180.94 seconds
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:12:41.695Z [MS] [INFO] Added message(id: 6 len: 49) to queue MS_Q_LEGACY: (2)!
Message: DE 66 A1 98 78 DA 00 B0 02 0A 08 DD 3E 9D 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 10 5F C4 41 41 C7 33 3D 0F 00 6E 06 65 42 99 1D 8C 3E
2024-07-25T00:12:41.695Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:12:41.695Z [BM_TX] [DEBUG] Message:
0f 00 10 5f c4 41 41 c7
33 3d 0f 00 6e 06 65 42
99 1d 8c 3e
2024-07-25T00:12:53.460Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:12:53.464Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:12:53.562Z [IRI] [INFO] Attempting to transmit. Time remaining: 165.85 seconds
FFT begin.
FFT complete.
sample analysis complete
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:13:11.683Z [MS] [INFO] Added message(id: 7 len: 49) to queue MS_Q_LEGACY: (3)!
Message: DE 66 A1 98 96 DA 01 B0 02 0A 10 DD 3E 9D 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 67 A5 C5 41 AF 8C 43 3D 0F 00 F3 8E 61 42 23 05 83 3E
2024-07-25T00:13:11.683Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:13:11.683Z [BM_TX] [DEBUG] Message:
0f 00 67 a5 c5 41 af 8c
43 3d 0f 00 f3 8e 61 42
23 05 83 3e
2024-07-25T00:13:12.804Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:13:12.804Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:13:12.902Z [IRI] [INFO] Attempting to transmit. Time remaining: 146.50 seconds
2024-07-25T00:13:29.722Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:13:29.726Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:13:29.824Z [IRI] [INFO] Attempting to transmit. Time remaining: 129.58 seconds
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:13:41.683Z [MS] [INFO] Added message(id: 8 len: 49) to queue MS_Q_LEGACY: (4)!
Message: DE 66 A1 98 B4 DA 01 B0 02 0A 18 DD 3E 9D 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 4D F0 C6 41 F4 89 35 3D 0F 00 77 AB 5E 42 36 53 4B 3E
2024-07-25T00:13:41.683Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:13:41.683Z [BM_TX] [DEBUG] Message:
0f 00 4d f0 c6 41 f4 89
35 3d 0f 00 77 ab 5e 42
36 53 4b 3e
2024-07-25T00:13:47.003Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:13:47.007Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:13:47.105Z [IRI] [INFO] Attempting to transmit. Time remaining: 112.30 seconds
FFT begin.
FFT complete.
sample analysis complete
2024-07-25T00:14:04.285Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:14:04.289Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:14:04.386Z [IRI] [INFO] Attempting to transmit. Time remaining: 95.01 seconds
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:14:11.683Z [MS] [INFO] Added message(id: 9 len: 49) to queue MS_Q_LEGACY: (5)!
Message: DE 66 A1 98 D2 DA 01 B0 02 0A 18 DD 3E 9D 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 A3 22 C8 41 19 13 2F 3D 0F 00 24 3A 5C 42 3D 84 2A 3E
2024-07-25T00:14:11.683Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:14:11.687Z [BM_TX] [DEBUG] Message:
0f 00 a3 22 c8 41 19 13
2f 3d 0f 00 24 3a 5c 42
3d 84 2a 3e
2024-07-25T00:14:15.589Z [GPS] [INFO] Max CNO changed from 43 to 47
2024-07-25T00:14:18.777Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:14:18.777Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:14:18.878Z [IRI] [INFO] Attempting to transmit. Time remaining: 80.52 seconds
2024-07-25T00:14:31.738Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:14:31.738Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:14:31.839Z [IRI] [INFO] Attempting to transmit. Time remaining: 67.55 seconds
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:14:41.683Z [MS] [INFO] Added message(id: 10 len: 49) to queue MS_Q_LEGACY: (6)!
Message: DE 66 A1 98 F0 DA 01 B0 02 0E 08 DD 3E 9D 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 0C 66 C9 41 97 35 37 3D 0F 00 84 21 5A 42 EF 75 0C 3E
2024-07-25T00:14:41.687Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:14:41.687Z [BM_TX] [DEBUG] Message:
0f 00 0c 66 c9 41 97 35
37 3d 0f 00 84 21 5a 42
ef 75 0c 3e
FFT begin.
FFT complete.
sample analysis complete
2024-07-25T00:14:44.878Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:14:44.878Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:14:44.980Z [IRI] [INFO] Attempting to transmit. Time remaining: 54.41 seconds
2024-07-25T00:14:57.839Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:14:57.839Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:14:57.941Z [IRI] [INFO] Attempting to transmit. Time remaining: 41.45 seconds
2024-07-25T00:15:06.839Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:15:06.839Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:15:06.941Z [IRI] [INFO] Attempting to transmit. Time remaining: 32.45 seconds
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:15:11.683Z [MS] [INFO] Added message(id: 11 len: 49) to queue MS_Q_LEGACY: (7)!
Message: DE 66 A1 99 0E DA 01 B0 02 0D F8 DD 3E 9D 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 F9 94 CA 41 E3 55 2C 3D 0F 00 30 57 58 42 73 1C F3 3D
2024-07-25T00:15:11.687Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:15:11.687Z [BM_TX] [DEBUG] Message:
0f 00 f9 94 ca 41 e3 55
2c 3d 0f 00 30 57 58 42
73 1c f3 3d
2024-07-25T00:15:15.839Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:15:15.839Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:15:15.941Z [IRI] [INFO] Attempting to transmit. Time remaining: 23.45 seconds
2024-07-25T00:15:24.839Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:15:24.839Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:15:24.941Z [IRI] [INFO] Attempting to transmit. Time remaining: 14.44 seconds
FFT begin.
FFT complete.
sample analysis complete
2024-07-25T00:15:33.839Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:15:33.843Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:15:33.941Z [IRI] [INFO] Attempting to transmit. Time remaining: 5.44 seconds
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:15:41.683Z [MS] [INFO] Added message(id: 12 len: 49) to queue MS_Q_LEGACY: (8)!
Message: DE 66 A1 99 2C DA 01 B0 02 0D F8 DD 3E 9D 85 33 C0 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 89 CE CB 41 7F 9D 3A 3D 0F 00 32 7C 56 42 5B AB 39 3E
2024-07-25T00:15:41.687Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:15:41.687Z [BM_TX] [DEBUG] Message:
0f 00 89 ce cb 41 7f 9d
3a 3d 0f 00 32 7c 56 42
5b ab 39 3e
2024-07-25T00:15:46.804Z [IRI] [INFO] Message not sent. 32
2024-07-25T00:15:46.804Z [IRI] [INFO] Waiting 0.10 seconds before retrying.
2024-07-25T00:15:46.902Z [IRI] [WARNING] Done trying to send
2024-07-25T00:15:46.902Z [IRI] [WARNING] Unable to transmit buffer
2024-07-25T00:15:46.906Z [MS] [WARNING] Message failed to send.
2024-07-25T00:15:46.906Z [ORC] [INFO] Iridium modem disabled
2024-07-25T00:15:46.906Z [MS] [DEBUG] Requeueing unexpired message 4 to queue(8)
2024-07-25T00:15:46.906Z [MS] [INFO] Entering Backoff state for 600000 ms
Current bit offset: 230
Rounded bit offset: 232
New bit offset: 392
2024-07-25T00:16:11.683Z [MS] [INFO] Added message(id: 13 len: 49) to queue MS_Q_LEGACY: (10)!
Message: DE 66 A1 99 4A DA 01 B0 02 11 F0 DD 3E A5 85 33 C4 0B 80 93 D3 D8 18 17 94 9D 03 42 74 0F 00 83 FE CC 41 21 60 2F 3D 0F 00 A4 1F 55 42 50 67 16 3E
2024-07-25T00:16:11.687Z [BM_TX] [INFO] Submitted spotter/transmit-data message to sat/cell queue, Len: 21
2024-07-25T00:16:11.687Z [BM_TX] [DEBUG] Message:
0f 00 83 fe cc 41 21 60
2f 3d 0f 00 a4 1f 55 42
50 67 16 3e
FFT begin.
FFT complete.
sample analysis complete