2024-08-30T16:52:11.809Z,1725036731.809 [CommandExec](IMPORTANT): got command restart logs 2024-08-30T16:53:40.278Z,1725036820.278 [NAL9602](INFO): SBD MO Status=0, MOMSN=56377, MT Status=0, MTMSN=0 2024-08-30T16:53:40.279Z,1725036820.279 [NAL9602](INFO): No messages in MT queue 2024-08-30T16:54:10.961Z,1725036850.961 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T16:55:11.570Z,1725036911.570 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:55:24.898Z,1725036924.898 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:55:38.230Z,1725036938.230 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:55:51.568Z,1725036951.568 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:56:04.912Z,1725036964.912 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:56:19.038Z,1725036979.038 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:56:32.774Z,1725036992.774 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:56:46.106Z,1725037006.106 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:56:56.621Z,1725037016.621 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T16:56:56.621Z,1725037016.621 [Default:CheckIn:C.Wait] Stopped 2024-08-30T16:56:56.621Z,1725037016.621 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T16:56:56.622Z,1725037016.622 [Default:CheckIn:D] Running Loop=1 2024-08-30T16:56:57.135Z,1725037017.135 [Default:CheckIn:D] Stopped 2024-08-30T16:56:57.135Z,1725037017.135 [Default:CheckIn:E] Running Loop=1 2024-08-30T16:56:57.469Z,1725037017.469 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.123289 min 2024-08-30T16:56:57.469Z,1725037017.469 [Default:CheckIn:E] Stopped 2024-08-30T16:56:57.469Z,1725037017.469 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T16:56:57.469Z,1725037017.469 [Default:CheckIn] Stopped 2024-08-30T16:56:57.469Z,1725037017.469 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T16:56:57.470Z,1725037017.470 [Default:CheckIn](INFO): Running loop #4 2024-08-30T16:56:57.470Z,1725037017.470 [Default:CheckIn] Running Loop=4 2024-08-30T16:56:57.470Z,1725037017.470 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T16:56:57.470Z,1725037017.470 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T16:56:58.630Z,1725037018.630 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:56:59.479Z,1725037019.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165655.00,A,3647.76188,N,12149.01903,W,29.857,79.92,300824,,,A*73 2024-08-30T16:56:59.481Z,1725037019.481 [NAL9602](INFO): GPS fix at 20240830T165655: (36.796031, -121.816984) 2024-08-30T16:56:59.524Z,1725037019.524 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T16:56:59.524Z,1725037019.524 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T16:57:07.123Z,1725037027.123 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240830T000125/Courier0091.lzma 2024-08-30T16:57:08.126Z,1725037028.126 [DataOverHttps](INFO): Moved sent file to Logs/20240830T000125/Courier0091.lzma.bak 2024-08-30T16:57:08.126Z,1725037028.126 [DataOverHttps](INFO): SBD MOMSN=23100773 2024-08-30T16:57:11.976Z,1725037031.976 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:57:13.582Z,1725037033.582 [NAL9602](INFO): SBD MO Status=0, MOMSN=56378, MT Status=0, MTMSN=0 2024-08-30T16:57:13.582Z,1725037033.582 [NAL9602](INFO): No messages in MT queue 2024-08-30T16:57:24.193Z,1725037044.193 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20240830T165211/Courier0000.lzma 2024-08-30T16:57:25.193Z,1725037045.193 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0000.lzma.bak 2024-08-30T16:57:25.194Z,1725037045.194 [DataOverHttps](INFO): SBD MOMSN=23100821 2024-08-30T16:57:25.294Z,1725037045.294 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T16:57:30.554Z,1725037050.554 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 4. 2024-08-30T16:57:30.556Z,1725037050.556 [BPC1](INFO): Received data from all battery sticks. 2024-08-30T16:57:41.092Z,1725037061.092 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20240830T000125/Express0092.lzma 2024-08-30T16:57:42.094Z,1725037062.094 [DataOverHttps](INFO): Moved sent file to Logs/20240830T000125/Express0092.lzma.bak 2024-08-30T16:57:42.094Z,1725037062.094 [DataOverHttps](INFO): SBD MOMSN=23100826 2024-08-30T16:57:44.289Z,1725037064.289 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T16:57:58.520Z,1725037078.520 [DataOverHttps](INFO): Sending 393 bytes from file Logs/20240830T165211/Express0001.lzma 2024-08-30T16:57:59.521Z,1725037079.521 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0001.lzma.bak 2024-08-30T16:57:59.522Z,1725037079.522 [DataOverHttps](INFO): SBD MOMSN=23100830 2024-08-30T16:58:15.311Z,1725037095.311 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20240830T165211/Express0004.lzma 2024-08-30T16:58:16.314Z,1725037096.314 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0004.lzma.bak 2024-08-30T16:58:16.314Z,1725037096.314 [DataOverHttps](INFO): SBD MOMSN=23100835 2024-08-30T16:58:17.834Z,1725037097.834 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T16:58:17.834Z,1725037097.834 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T16:58:17.834Z,1725037097.834 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:03:18.351Z,1725037398.351 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:03:18.351Z,1725037398.351 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:03:18.351Z,1725037398.351 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:03:18.351Z,1725037398.351 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:03:18.733Z,1725037398.733 [Default:CheckIn:D] Stopped 2024-08-30T17:03:18.733Z,1725037398.733 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:03:19.139Z,1725037399.139 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.483256 min 2024-08-30T17:03:19.139Z,1725037399.139 [Default:CheckIn:E] Stopped 2024-08-30T17:03:19.139Z,1725037399.139 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:03:19.139Z,1725037399.139 [Default:CheckIn] Stopped 2024-08-30T17:03:19.139Z,1725037399.139 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:03:19.139Z,1725037399.139 [Default:CheckIn](INFO): Running loop #5 2024-08-30T17:03:19.140Z,1725037399.140 [Default:CheckIn] Running Loop=5 2024-08-30T17:03:19.140Z,1725037399.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:03:19.140Z,1725037399.140 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:03:21.151Z,1725037401.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170317.00,A,3648.35240,N,12147.13145,W,3.013,183.49,300824,,,A*7E 2024-08-30T17:03:21.153Z,1725037401.153 [NAL9602](INFO): GPS fix at 20240830T170317: (36.805873, -121.785524) 2024-08-30T17:03:21.179Z,1725037401.179 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:03:21.179Z,1725037401.179 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:03:28.840Z,1725037408.840 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240830T165211/Courier0006.lzma 2024-08-30T17:03:29.842Z,1725037409.842 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0006.lzma.bak 2024-08-30T17:03:29.842Z,1725037409.842 [DataOverHttps](INFO): SBD MOMSN=23100871 2024-08-30T17:03:42.962Z,1725037422.962 [NAL9602](INFO): SBD MO Status=2, MOMSN=56379, MT Status=2, MTMSN=0 2024-08-30T17:03:42.963Z,1725037422.963 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-30T17:03:45.752Z,1725037425.752 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240830T165211/Express0007.lzma 2024-08-30T17:03:46.754Z,1725037426.754 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0007.lzma.bak 2024-08-30T17:03:46.754Z,1725037426.754 [DataOverHttps](INFO): SBD MOMSN=23100875 2024-08-30T17:03:48.229Z,1725037428.229 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:03:48.229Z,1725037428.229 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:03:48.229Z,1725037428.229 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:04:00.334Z,1725037440.334 [NAL9602](INFO): SBD MO Status=2, MOMSN=56379, MT Status=2, MTMSN=0 2024-08-30T17:04:00.335Z,1725037440.335 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-30T17:04:16.089Z,1725037456.089 [NAL9602](INFO): SBD MO Status=2, MOMSN=56379, MT Status=2, MTMSN=0 2024-08-30T17:04:16.089Z,1725037456.089 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-30T17:05:09.537Z,1725037509.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=56379, MT Status=2, MTMSN=0 2024-08-30T17:05:09.537Z,1725037509.537 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-30T17:08:11.458Z,1725037691.458 [NAL9602](INFO): SBD MO Status=0, MOMSN=56379, MT Status=0, MTMSN=0 2024-08-30T17:08:11.459Z,1725037691.459 [NAL9602](INFO): No messages in MT queue 2024-08-30T17:08:42.208Z,1725037722.208 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T17:08:48.686Z,1725037728.686 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:08:48.686Z,1725037728.686 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:08:48.687Z,1725037728.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:08:48.687Z,1725037728.687 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:08:49.097Z,1725037729.097 [Default:CheckIn:D] Stopped 2024-08-30T17:08:49.097Z,1725037729.097 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:08:49.509Z,1725037729.509 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.989323 min 2024-08-30T17:08:49.509Z,1725037729.509 [Default:CheckIn:E] Stopped 2024-08-30T17:08:49.509Z,1725037729.509 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:08:49.509Z,1725037729.509 [Default:CheckIn] Stopped 2024-08-30T17:08:49.509Z,1725037729.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:08:49.509Z,1725037729.509 [Default:CheckIn](INFO): Running loop #6 2024-08-30T17:08:49.509Z,1725037729.509 [Default:CheckIn] Running Loop=6 2024-08-30T17:08:49.510Z,1725037729.510 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:08:49.510Z,1725037729.510 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:08:51.516Z,1725037731.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170847.00,A,3648.15065,N,12147.22251,W,0.525,216.09,300824,,,A*7B 2024-08-30T17:08:51.518Z,1725037731.518 [NAL9602](INFO): GPS fix at 20240830T170847: (36.802511, -121.787042) 2024-08-30T17:08:51.529Z,1725037731.529 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:08:51.529Z,1725037731.529 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:09:02.227Z,1725037742.227 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240830T165211/Courier0009.lzma 2024-08-30T17:09:03.230Z,1725037743.230 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0009.lzma.bak 2024-08-30T17:09:03.230Z,1725037743.230 [DataOverHttps](INFO): SBD MOMSN=23101092 2024-08-30T17:09:19.017Z,1725037759.017 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240830T165211/Express0010.lzma 2024-08-30T17:09:20.014Z,1725037760.014 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0010.lzma.bak 2024-08-30T17:09:20.014Z,1725037760.014 [DataOverHttps](INFO): SBD MOMSN=23101098 2024-08-30T17:09:21.463Z,1725037761.463 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:09:21.463Z,1725037761.463 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:09:21.463Z,1725037761.463 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:09:22.670Z,1725037762.670 [NAL9602](INFO): SBD MO Status=0, MOMSN=56380, MT Status=0, MTMSN=0 2024-08-30T17:09:22.670Z,1725037762.670 [NAL9602](INFO): No messages in MT queue 2024-08-30T17:09:53.367Z,1725037793.367 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T17:14:21.874Z,1725038061.874 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:14:21.874Z,1725038061.874 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:14:21.875Z,1725038061.875 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:14:21.875Z,1725038061.875 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:14:22.278Z,1725038062.278 [Default:CheckIn:D] Stopped 2024-08-30T17:14:22.279Z,1725038062.279 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:14:22.674Z,1725038062.674 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.542350 min 2024-08-30T17:14:22.674Z,1725038062.674 [Default:CheckIn:E] Stopped 2024-08-30T17:14:22.674Z,1725038062.674 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:14:22.674Z,1725038062.674 [Default:CheckIn] Stopped 2024-08-30T17:14:22.674Z,1725038062.674 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:14:22.674Z,1725038062.674 [Default:CheckIn](INFO): Running loop #7 2024-08-30T17:14:22.674Z,1725038062.674 [Default:CheckIn] Running Loop=7 2024-08-30T17:14:22.675Z,1725038062.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:14:22.675Z,1725038062.675 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:14:24.687Z,1725038064.687 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171420.00,A,3648.17900,N,12147.27879,W,0.058,292.30,300824,,,A*73 2024-08-30T17:14:24.689Z,1725038064.689 [NAL9602](INFO): GPS fix at 20240830T171420: (36.802983, -121.787980) 2024-08-30T17:14:24.699Z,1725038064.699 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:14:24.699Z,1725038064.699 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:14:33.316Z,1725038073.316 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240830T165211/Courier0012.lzma 2024-08-30T17:14:34.318Z,1725038074.318 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0012.lzma.bak 2024-08-30T17:14:34.318Z,1725038074.318 [DataOverHttps](INFO): SBD MOMSN=23101220 2024-08-30T17:14:50.140Z,1725038090.140 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240830T165211/Express0013.lzma 2024-08-30T17:14:51.142Z,1725038091.142 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0013.lzma.bak 2024-08-30T17:14:51.142Z,1725038091.142 [DataOverHttps](INFO): SBD MOMSN=23101223 2024-08-30T17:14:53.055Z,1725038093.055 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:14:53.056Z,1725038093.056 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:14:53.056Z,1725038093.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:18:37.869Z,1725038317.869 [NAL9602](INFO): SBD MO Status=2, MOMSN=56381, MT Status=2, MTMSN=0 2024-08-30T17:18:37.869Z,1725038317.869 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-30T17:19:27.561Z,1725038367.561 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-30T17:19:53.829Z,1725038393.829 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:19:53.829Z,1725038393.829 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:19:53.829Z,1725038393.829 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:19:53.830Z,1725038393.830 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:19:54.238Z,1725038394.238 [Default:CheckIn:D] Stopped 2024-08-30T17:19:54.238Z,1725038394.238 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:19:54.638Z,1725038394.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.075012 min 2024-08-30T17:19:54.638Z,1725038394.638 [Default:CheckIn:E] Stopped 2024-08-30T17:19:54.638Z,1725038394.638 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:19:54.638Z,1725038394.638 [Default:CheckIn] Stopped 2024-08-30T17:19:54.639Z,1725038394.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:19:54.639Z,1725038394.639 [Default:CheckIn](INFO): Running loop #8 2024-08-30T17:19:54.639Z,1725038394.639 [Default:CheckIn] Running Loop=8 2024-08-30T17:19:54.639Z,1725038394.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:19:54.639Z,1725038394.639 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:19:56.655Z,1725038396.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171952.00,A,3648.17305,N,12147.27875,W,0.350,164.16,300824,,,A*7D 2024-08-30T17:19:56.657Z,1725038396.657 [NAL9602](INFO): GPS fix at 20240830T171952: (36.802884, -121.787979) 2024-08-30T17:19:56.683Z,1725038396.683 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:19:56.683Z,1725038396.683 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:20:06.927Z,1725038406.927 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240830T165211/Courier0015.lzma 2024-08-30T17:20:07.930Z,1725038407.930 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0015.lzma.bak 2024-08-30T17:20:07.930Z,1725038407.930 [DataOverHttps](INFO): SBD MOMSN=23101298 2024-08-30T17:20:27.376Z,1725038427.376 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20240830T165211/Express0016.lzma 2024-08-30T17:20:28.378Z,1725038428.378 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0016.lzma.bak 2024-08-30T17:20:28.378Z,1725038428.378 [DataOverHttps](INFO): SBD MOMSN=23101302 2024-08-30T17:20:28.621Z,1725038428.621 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T17:20:30.283Z,1725038430.283 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:20:30.283Z,1725038430.283 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:20:30.283Z,1725038430.283 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:25:30.838Z,1725038730.838 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:25:30.838Z,1725038730.838 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:25:30.838Z,1725038730.838 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:25:30.838Z,1725038730.838 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:25:31.242Z,1725038731.242 [Default:CheckIn:D] Stopped 2024-08-30T17:25:31.243Z,1725038731.243 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.691752 min 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn:E] Stopped 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn] Stopped 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn](INFO): Running loop #9 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn] Running Loop=9 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:25:31.651Z,1725038731.651 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:25:33.663Z,1725038733.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172529.00,A,3648.18424,N,12147.27696,W,0.311,191.59,300824,,,A*72 2024-08-30T17:25:33.674Z,1725038733.674 [NAL9602](INFO): GPS fix at 20240830T172529: (36.803071, -121.787949) 2024-08-30T17:25:33.684Z,1725038733.684 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:25:33.684Z,1725038733.684 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:25:41.771Z,1725038741.771 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240830T165211/Courier0018.lzma 2024-08-30T17:25:42.774Z,1725038742.774 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0018.lzma.bak 2024-08-30T17:25:42.774Z,1725038742.774 [DataOverHttps](INFO): SBD MOMSN=23101333 2024-08-30T17:26:04.385Z,1725038764.385 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-08-30T17:26:04.460Z,1725038764.460 [NAL9602](ERROR): received: +CSQ:1 OK381, 2, 0, 0, 0 OK 2024-08-30T17:26:22.304Z,1725038782.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-08-30T17:26:32.284Z,1725038792.284 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240830T165211/Express0019.lzma 2024-08-30T17:26:33.286Z,1725038793.286 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0019.lzma.bak 2024-08-30T17:26:33.286Z,1725038793.286 [DataOverHttps](INFO): SBD MOMSN=23101336 2024-08-30T17:26:34.731Z,1725038794.731 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:26:34.732Z,1725038794.732 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:26:34.732Z,1725038794.732 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:26:54.915Z,1725038814.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=56381, MT Status=2, MTMSN=0 2024-08-30T17:26:54.915Z,1725038814.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-30T17:28:56.922Z,1725038936.922 [NAL9602](INFO): SBD MO Status=2, MOMSN=56381, MT Status=2, MTMSN=0 2024-08-30T17:28:56.922Z,1725038936.922 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-08-30T17:29:32.078Z,1725038972.078 [NAL9602](INFO): SBD MO Status=0, MOMSN=56381, MT Status=0, MTMSN=0 2024-08-30T17:29:32.078Z,1725038972.078 [NAL9602](INFO): No messages in MT queue 2024-08-30T17:30:02.777Z,1725039002.777 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T17:31:35.323Z,1725039095.323 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:31:35.323Z,1725039095.323 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:31:35.323Z,1725039095.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:31:35.323Z,1725039095.323 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:31:35.723Z,1725039095.723 [Default:CheckIn:D] Stopped 2024-08-30T17:31:35.723Z,1725039095.723 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:31:36.130Z,1725039096.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.766418 min 2024-08-30T17:31:36.130Z,1725039096.130 [Default:CheckIn:E] Stopped 2024-08-30T17:31:36.130Z,1725039096.130 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:31:36.131Z,1725039096.131 [Default:CheckIn] Stopped 2024-08-30T17:31:36.131Z,1725039096.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:31:36.131Z,1725039096.131 [Default:CheckIn](INFO): Running loop #10 2024-08-30T17:31:36.131Z,1725039096.131 [Default:CheckIn] Running Loop=10 2024-08-30T17:31:36.131Z,1725039096.131 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:31:36.131Z,1725039096.131 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:31:38.138Z,1725039098.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173134.00,A,3648.17094,N,12147.28184,W,0.175,272.31,300824,,,A*70 2024-08-30T17:31:38.140Z,1725039098.140 [NAL9602](INFO): GPS fix at 20240830T173134: (36.802849, -121.788031) 2024-08-30T17:31:38.174Z,1725039098.174 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:31:38.174Z,1725039098.174 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:31:45.399Z,1725039105.399 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240830T165211/Courier0021.lzma 2024-08-30T17:31:46.402Z,1725039106.402 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0021.lzma.bak 2024-08-30T17:31:46.402Z,1725039106.402 [DataOverHttps](INFO): SBD MOMSN=23101463 2024-08-30T17:32:02.436Z,1725039122.436 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240830T165211/Express0022.lzma 2024-08-30T17:32:03.438Z,1725039123.438 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0022.lzma.bak 2024-08-30T17:32:03.438Z,1725039123.438 [DataOverHttps](INFO): SBD MOMSN=23101469 2024-08-30T17:32:04.811Z,1725039124.811 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:32:04.811Z,1725039124.811 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:32:04.811Z,1725039124.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:32:29.450Z,1725039149.450 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-08-30T17:32:29.450Z,1725039149.450 [DropWeight] Hardware Fault, FailCount= 1 2024-08-30T17:32:29.450Z,1725039149.450 [DropWeight](ERROR): Hardware Fault 2024-08-30T17:32:29.475Z,1725039149.475 [CommandExec](FAULT): Scheduling is paused 2024-08-30T17:32:29.476Z,1725039149.476 [CBIT](INFO): Critical error at 20240830T173229 2024-08-30T17:32:29.479Z,1725039149.479 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-08-30T17:32:29.479Z,1725039149.479 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-08-30T17:32:29.887Z,1725039149.887 [CBIT](INFO): Critical error at 20240830T173229 2024-08-30T17:36:40.381Z,1725039400.381 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-30T17:37:05.438Z,1725039425.438 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:37:05.438Z,1725039425.438 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:37:05.438Z,1725039425.438 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:37:05.439Z,1725039425.439 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:37:05.850Z,1725039425.850 [Default:CheckIn:D] Stopped 2024-08-30T17:37:05.850Z,1725039425.850 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.268542 min 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn:E] Stopped 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn] Stopped 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn](INFO): Running loop #11 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn] Running Loop=11 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:37:06.247Z,1725039426.247 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:37:08.262Z,1725039428.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173704.00,A,3648.16414,N,12147.28416,W,0.156,178.73,300824,,,A*78 2024-08-30T17:37:08.265Z,1725039428.265 [NAL9602](INFO): GPS fix at 20240830T173704: (36.802736, -121.788069) 2024-08-30T17:37:08.275Z,1725039428.275 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:37:08.275Z,1725039428.275 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:37:15.648Z,1725039435.648 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20240830T165211/Courier0024.lzma 2024-08-30T17:37:16.650Z,1725039436.650 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0024.lzma.bak 2024-08-30T17:37:16.650Z,1725039436.650 [DataOverHttps](INFO): SBD MOMSN=23101600 2024-08-30T17:37:32.776Z,1725039452.776 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20240830T165211/Express0025.lzma 2024-08-30T17:37:33.410Z,1725039453.410 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0025.lzma.bak 2024-08-30T17:37:33.410Z,1725039453.410 [DataOverHttps](INFO): SBD MOMSN=23101611 2024-08-30T17:37:34.934Z,1725039454.934 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:37:34.934Z,1725039454.934 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:37:34.934Z,1725039454.934 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:37:40.572Z,1725039460.572 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T17:42:35.505Z,1725039755.505 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:42:35.505Z,1725039755.505 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:42:35.505Z,1725039755.505 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:42:35.505Z,1725039755.505 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:42:35.910Z,1725039755.910 [Default:CheckIn:D] Stopped 2024-08-30T17:42:35.910Z,1725039755.910 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:42:36.319Z,1725039756.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.769543 min 2024-08-30T17:42:36.319Z,1725039756.319 [Default:CheckIn:E] Stopped 2024-08-30T17:42:36.319Z,1725039756.319 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:42:36.319Z,1725039756.319 [Default:CheckIn] Stopped 2024-08-30T17:42:36.319Z,1725039756.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:42:36.320Z,1725039756.320 [Default:CheckIn](INFO): Running loop #12 2024-08-30T17:42:36.320Z,1725039756.320 [Default:CheckIn] Running Loop=12 2024-08-30T17:42:36.320Z,1725039756.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:42:36.320Z,1725039756.320 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:42:38.390Z,1725039758.390 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174234.00,A,3648.15013,N,12147.28055,W,0.544,207.85,300824,,,A*7F 2024-08-30T17:42:38.392Z,1725039758.392 [NAL9602](INFO): GPS fix at 20240830T174234: (36.802502, -121.788009) 2024-08-30T17:42:38.402Z,1725039758.402 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:42:38.402Z,1725039758.402 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:42:45.715Z,1725039765.715 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240830T165211/Courier0027.lzma 2024-08-30T17:42:46.718Z,1725039766.718 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0027.lzma.bak 2024-08-30T17:42:46.718Z,1725039766.718 [DataOverHttps](INFO): SBD MOMSN=23101718 2024-08-30T17:43:05.908Z,1725039785.908 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20240830T165211/Express0028.lzma 2024-08-30T17:43:06.910Z,1725039786.910 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0028.lzma.bak 2024-08-30T17:43:06.910Z,1725039786.910 [DataOverHttps](INFO): SBD MOMSN=23101721 2024-08-30T17:43:08.287Z,1725039788.287 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:43:08.287Z,1725039788.287 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:43:08.288Z,1725039788.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:45:31.580Z,1725039931.580 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-30T17:45:31.583Z,1725039931.583 [DATMMP](INFO): timesync rx: x2 x0 x0 x0 2024-08-30T17:45:31.583Z,1725039931.583 [DATMMP](INFO): Received message type: timesync 2024-08-30T17:45:31.583Z,1725039931.583 [DATMMP](INFO): Handled 2024-08-30T17:45:41.440Z,1725039941.440 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-30T17:45:41.441Z,1725039941.441 [DATMMP](INFO): timesync rx: x2 x1 x1 x0 2024-08-30T17:45:41.441Z,1725039941.441 [DATMMP](INFO): Received message type: timesync 2024-08-30T17:45:41.442Z,1725039941.442 [DATMMP](INFO): Handled 2024-08-30T17:45:51.528Z,1725039951.528 [DATMMP](INFO): GSXN notify for xid: 0 2024-08-30T17:45:51.529Z,1725039951.529 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-08-30T17:45:51.530Z,1725039951.530 [DATMMP](INFO): Received message type: timesync 2024-08-30T17:45:51.530Z,1725039951.530 [DATMMP](INFO): Handled 2024-08-30T17:47:29.675Z,1725040049.675 [CBIT](INFO): Clearing failed state for component DropWeight 2024-08-30T17:47:29.675Z,1725040049.675 [DropWeight] No Fault, FailCount= 1 2024-08-30T17:47:40.569Z,1725040060.569 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-30T17:48:08.864Z,1725040088.864 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:48:08.864Z,1725040088.864 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:48:08.864Z,1725040088.864 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:48:08.864Z,1725040088.864 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:48:09.259Z,1725040089.259 [Default:CheckIn:D] Stopped 2024-08-30T17:48:09.259Z,1725040089.259 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:48:09.661Z,1725040089.661 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.325358 min 2024-08-30T17:48:09.661Z,1725040089.661 [Default:CheckIn:E] Stopped 2024-08-30T17:48:09.662Z,1725040089.662 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:48:09.662Z,1725040089.662 [Default:CheckIn] Stopped 2024-08-30T17:48:09.662Z,1725040089.662 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:48:09.662Z,1725040089.662 [Default:CheckIn](INFO): Running loop #13 2024-08-30T17:48:09.662Z,1725040089.662 [Default:CheckIn] Running Loop=13 2024-08-30T17:48:09.662Z,1725040089.662 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:48:09.662Z,1725040089.662 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:48:50.058Z,1725040130.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174846.00,A,3648.16180,N,12147.28640,W,3.849,0.00,300824,,,A*71 2024-08-30T17:48:50.060Z,1725040130.060 [NAL9602](INFO): GPS fix at 20240830T174846: (36.802697, -121.788107) 2024-08-30T17:48:50.070Z,1725040130.070 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:48:50.070Z,1725040130.070 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:49:01.359Z,1725040141.359 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240830T165211/Courier0030.lzma 2024-08-30T17:49:02.362Z,1725040142.362 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0030.lzma.bak 2024-08-30T17:49:02.362Z,1725040142.362 [DataOverHttps](INFO): SBD MOMSN=23101781 2024-08-30T17:49:18.232Z,1725040158.232 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240830T165211/Express0031.lzma 2024-08-30T17:49:19.234Z,1725040159.234 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0031.lzma.bak 2024-08-30T17:49:19.234Z,1725040159.234 [DataOverHttps](INFO): SBD MOMSN=23101784 2024-08-30T17:49:20.816Z,1725040160.816 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:49:20.816Z,1725040160.816 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:49:20.816Z,1725040160.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:49:22.421Z,1725040162.421 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T17:54:21.582Z,1725040461.582 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T17:54:21.582Z,1725040461.582 [Default:CheckIn:C.Wait] Stopped 2024-08-30T17:54:21.582Z,1725040461.582 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T17:54:21.583Z,1725040461.583 [Default:CheckIn:D] Running Loop=1 2024-08-30T17:54:22.016Z,1725040462.016 [Default:CheckIn:D] Stopped 2024-08-30T17:54:22.016Z,1725040462.016 [Default:CheckIn:E] Running Loop=1 2024-08-30T17:54:22.432Z,1725040462.432 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.537980 min 2024-08-30T17:54:22.432Z,1725040462.432 [Default:CheckIn:E] Stopped 2024-08-30T17:54:22.432Z,1725040462.432 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T17:54:22.432Z,1725040462.432 [Default:CheckIn] Stopped 2024-08-30T17:54:22.432Z,1725040462.432 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T17:54:22.432Z,1725040462.432 [Default:CheckIn](INFO): Running loop #14 2024-08-30T17:54:22.433Z,1725040462.433 [Default:CheckIn] Running Loop=14 2024-08-30T17:54:22.433Z,1725040462.433 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T17:54:22.433Z,1725040462.433 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T17:54:55.105Z,1725040495.105 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-08-30T17:54:55.184Z,1725040495.184 [NAL9602](ERROR): received: +CSQ:0 OK381, 0, 0, 0, 0 OK 2024-08-30T17:58:36.516Z,1725040716.516 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T17:58:48.635Z,1725040728.635 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T17:59:00.352Z,1725040740.352 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T17:59:12.068Z,1725040752.068 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T17:59:22.601Z,1725040762.601 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-30T17:54:22.4Z 2024-08-30T17:59:22.601Z,1725040762.601 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T17:59:22.601Z,1725040762.601 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T17:59:24.186Z,1725040764.186 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T17:59:26.617Z,1725040766.617 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-30T17:59:30.147Z,1725040770.147 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240830T165211/Courier0033.lzma 2024-08-30T17:59:31.146Z,1725040771.146 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0033.lzma.bak 2024-08-30T17:59:31.146Z,1725040771.146 [DataOverHttps](INFO): SBD MOMSN=23101926 2024-08-30T17:59:36.306Z,1725040776.306 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T17:59:46.968Z,1725040786.968 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240830T165211/Express0034.lzma 2024-08-30T17:59:47.239Z,1725040787.239 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T17:59:47.972Z,1725040787.972 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0034.lzma.bak 2024-08-30T17:59:47.972Z,1725040787.972 [DataOverHttps](INFO): SBD MOMSN=23101928 2024-08-30T17:59:49.659Z,1725040789.659 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T17:59:49.659Z,1725040789.659 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T17:59:49.659Z,1725040789.659 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T17:59:57.321Z,1725040797.321 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T17:59:59.035Z,1725040799.035 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:02:30.968Z,1725040950.968 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:02:44.298Z,1725040964.298 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:02:58.070Z,1725040978.070 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:03:12.238Z,1725040992.238 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:03:13.893Z,1725040993.893 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-30T18:03:24.819Z,1725041004.819 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002220 CHAN A1 (24V): 0.000243 CHAN A2 (12V): -0.002197 CHAN A3 (5V): -0.001300 CHAN B0 (3.3V): -0.000136 CHAN B1 (3.15aV): 0.000080 CHAN B2 (3.15bV): -0.000095 CHAN B3 (GND): -0.000194 OPEN: -0.000586 Full Scale: +/- 1 mA 2024-08-30T18:03:25.570Z,1725041005.570 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:03:38.902Z,1725041018.902 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:03:52.236Z,1725041032.236 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:04:05.566Z,1725041045.566 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:04:18.904Z,1725041058.904 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:04:32.264Z,1725041072.264 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:04:45.590Z,1725041085.590 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-08-30T18:04:50.454Z,1725041090.454 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T18:04:50.454Z,1725041090.454 [Default:CheckIn:C.Wait] Stopped 2024-08-30T18:04:50.454Z,1725041090.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T18:04:50.454Z,1725041090.454 [Default:CheckIn:D] Running Loop=1 2024-08-30T18:04:50.856Z,1725041090.856 [Default:CheckIn:D] Stopped 2024-08-30T18:04:50.856Z,1725041090.856 [Default:CheckIn:E] Running Loop=1 2024-08-30T18:04:51.268Z,1725041091.268 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.018636 min 2024-08-30T18:04:51.268Z,1725041091.268 [Default:CheckIn:E] Stopped 2024-08-30T18:04:51.268Z,1725041091.268 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T18:04:51.268Z,1725041091.268 [Default:CheckIn] Stopped 2024-08-30T18:04:51.268Z,1725041091.268 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T18:04:51.268Z,1725041091.268 [Default:CheckIn](INFO): Running loop #15 2024-08-30T18:04:51.269Z,1725041091.269 [Default:CheckIn] Running Loop=15 2024-08-30T18:04:51.269Z,1725041091.269 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T18:04:51.269Z,1725041091.269 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T18:04:52.878Z,1725041092.878 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5. 2024-08-30T18:04:52.881Z,1725041092.881 [BPC1](INFO): Received data from all battery sticks. 2024-08-30T18:04:52.914Z,1725041092.914 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-08-30T18:04:52.914Z,1725041092.914 [NAL9602] Data Fault, FailCount= 1 2024-08-30T18:04:52.914Z,1725041092.914 [NAL9602](ERROR): Data Fault 2024-08-30T18:04:52.968Z,1725041092.968 [CBIT](ERROR): Data Fault in component: NAL9602 2024-08-30T18:04:53.269Z,1725041093.269 [NAL9602](INFO): Powering down 2024-08-30T18:04:54.096Z,1725041094.096 [CBIT](INFO): Clearing failed state for component NAL9602 2024-08-30T18:04:54.096Z,1725041094.096 [NAL9602] No Fault, FailCount= 1 2024-08-30T18:05:23.640Z,1725041123.640 [NAL9602](INFO): Powering up NAL9602 2024-08-30T18:05:34.553Z,1725041134.553 [NAL9602](INFO): NAL9602 initialized 2024-08-30T18:09:51.532Z,1725041391.532 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-30T18:04:51.3Z 2024-08-30T18:09:51.532Z,1725041391.532 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T18:09:51.532Z,1725041391.532 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T18:10:00.311Z,1725041400.311 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240830T165211/Courier0036.lzma 2024-08-30T18:10:01.314Z,1725041401.314 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0036.lzma.bak 2024-08-30T18:10:01.314Z,1725041401.314 [DataOverHttps](INFO): SBD MOMSN=23102113 2024-08-30T18:10:17.054Z,1725041417.054 [DataOverHttps](INFO): Sending 418 bytes from file Logs/20240830T165211/Express0037.lzma 2024-08-30T18:10:18.055Z,1725041418.055 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0037.lzma.bak 2024-08-30T18:10:18.055Z,1725041418.055 [DataOverHttps](INFO): SBD MOMSN=23102122 2024-08-30T18:10:19.404Z,1725041419.404 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T18:10:19.404Z,1725041419.404 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T18:10:19.404Z,1725041419.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T18:10:38.437Z,1725041438.437 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-30T18:11:09.199Z,1725041469.199 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T18:15:19.897Z,1725041719.897 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T18:15:19.898Z,1725041719.898 [Default:CheckIn:C.Wait] Stopped 2024-08-30T18:15:19.898Z,1725041719.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T18:15:19.898Z,1725041719.898 [Default:CheckIn:D] Running Loop=1 2024-08-30T18:15:20.289Z,1725041720.289 [Default:CheckIn:D] Stopped 2024-08-30T18:15:20.289Z,1725041720.289 [Default:CheckIn:E] Running Loop=1 2024-08-30T18:15:20.699Z,1725041720.699 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.509196 min 2024-08-30T18:15:20.699Z,1725041720.699 [Default:CheckIn:E] Stopped 2024-08-30T18:15:20.699Z,1725041720.699 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T18:15:20.699Z,1725041720.699 [Default:CheckIn] Stopped 2024-08-30T18:15:20.699Z,1725041720.699 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T18:15:20.700Z,1725041720.700 [Default:CheckIn](INFO): Running loop #16 2024-08-30T18:15:20.700Z,1725041720.700 [Default:CheckIn] Running Loop=16 2024-08-30T18:15:20.700Z,1725041720.700 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T18:15:20.700Z,1725041720.700 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T18:15:53.417Z,1725041753.417 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-08-30T18:15:53.501Z,1725041753.501 [NAL9602](ERROR): received: +CSQ:0 OK381, 0, 0, 0, 0 OK 2024-08-30T18:17:31.211Z,1725041851.211 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-08-30T18:17:31.211Z,1725041851.211 [DropWeight] Hardware Fault, FailCount= 1 2024-08-30T18:17:31.211Z,1725041851.211 [DropWeight](ERROR): Hardware Fault 2024-08-30T18:17:31.229Z,1725041851.229 [CBIT](INFO): Critical error at 20240830T181731 2024-08-30T18:17:31.231Z,1725041851.231 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-08-30T18:17:31.232Z,1725041851.232 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-08-30T18:17:31.643Z,1725041851.643 [CBIT](INFO): Critical error at 20240830T181731 2024-08-30T18:20:20.921Z,1725042020.921 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-30T18:15:20.7Z 2024-08-30T18:20:20.921Z,1725042020.921 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T18:20:20.921Z,1725042020.921 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-08-30T18:20:25.386Z,1725042025.386 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-30T18:20:31.292Z,1725042031.292 [DataOverHttps](INFO): Sending 114 bytes from file Logs/20240830T165211/Courier0039.lzma 2024-08-30T18:20:32.294Z,1725042032.294 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Courier0039.lzma.bak 2024-08-30T18:20:32.294Z,1725042032.294 [DataOverHttps](INFO): SBD MOMSN=23102412 2024-08-30T18:20:51.364Z,1725042051.364 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240830T165211/Express0040.lzma 2024-08-30T18:20:52.366Z,1725042052.366 [DataOverHttps](INFO): Moved sent file to Logs/20240830T165211/Express0040.lzma.bak 2024-08-30T18:20:52.366Z,1725042052.366 [DataOverHttps](INFO): SBD MOMSN=23102416 2024-08-30T18:20:54.112Z,1725042054.112 [Default:CheckIn:Read_Iridium] Stopped 2024-08-30T18:20:54.112Z,1725042054.112 [Default:CheckIn:C.Wait] Running Loop=1 2024-08-30T18:20:54.112Z,1725042054.112 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-08-30T18:20:56.121Z,1725042056.121 [NAL9602](INFO): Not Powering down - fast GPS 2024-08-30T18:25:54.810Z,1725042354.810 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-08-30T18:25:54.810Z,1725042354.810 [Default:CheckIn:C.Wait] Stopped 2024-08-30T18:25:54.810Z,1725042354.810 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-08-30T18:25:54.810Z,1725042354.810 [Default:CheckIn:D] Running Loop=1 2024-08-30T18:25:55.209Z,1725042355.209 [Default:CheckIn:D] Stopped 2024-08-30T18:25:55.209Z,1725042355.209 [Default:CheckIn:E] Running Loop=1 2024-08-30T18:25:55.610Z,1725042355.610 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.091195 min 2024-08-30T18:25:55.611Z,1725042355.611 [Default:CheckIn:E] Stopped 2024-08-30T18:25:55.611Z,1725042355.611 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-08-30T18:25:55.611Z,1725042355.611 [Default:CheckIn] Stopped 2024-08-30T18:25:55.611Z,1725042355.611 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T18:25:55.611Z,1725042355.611 [Default:CheckIn](INFO): Running loop #17 2024-08-30T18:25:55.611Z,1725042355.611 [Default:CheckIn] Running Loop=17 2024-08-30T18:25:55.611Z,1725042355.611 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-30T18:25:55.611Z,1725042355.611 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-30T18:25:57.217Z,1725042357.217 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-08-30T18:25:57.217Z,1725042357.217 [NAL9602] Data Fault, FailCount= 2 2024-08-30T18:25:57.217Z,1725042357.217 [NAL9602](ERROR): Data Fault 2024-08-30T18:25:57.236Z,1725042357.236 [CBIT](ERROR): Data Fault in component: NAL9602 2024-08-30T18:25:57.621Z,1725042357.621 [NAL9602](INFO): Powering down 2024-08-30T18:25:58.460Z,1725042358.460 [CBIT](INFO): Clearing failed state for component NAL9602 2024-08-30T18:25:58.460Z,1725042358.460 [NAL9602] No Fault, FailCount= 2 2024-08-30T18:26:27.917Z,1725042387.917 [NAL9602](INFO): Powering up NAL9602 2024-08-30T18:26:38.833Z,1725042398.833 [NAL9602](INFO): NAL9602 initialized 2024-08-30T18:26:47.624Z,1725042407.624 [CommandExec](IMPORTANT): got command quit 2024-08-30T18:26:48.628Z,1725042408.628 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:48.628Z,1725042408.628 [CommandExec](INFO): Uninitializing the command executive. 2024-08-30T18:26:48.628Z,1725042408.628 [CommandExec](INFO): Uninitializing the command scheduler. 2024-08-30T18:26:48.628Z,1725042408.628 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:48.685Z,1725042408.685 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-08-30T18:26:48.685Z,1725042408.685 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-08-30T18:26:48.685Z,1725042408.685 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:48.686Z,1725042408.686 [NavChartDb](INFO): Join timeout helper Thread ID is 1455 2024-08-30T18:26:48.698Z,1725042408.698 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:48.698Z,1725042408.698 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:48.708Z,1725042408.708 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-08-30T18:26:48.708Z,1725042408.708 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:48.709Z,1725042408.709 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1456 2024-08-30T18:26:49.112Z,1725042409.112 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:49.112Z,1725042409.112 [WetLabsBB2FL](INFO): Powering down 2024-08-30T18:26:49.113Z,1725042409.113 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:49.124Z,1725042409.124 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-08-30T18:26:49.124Z,1725042409.124 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:49.124Z,1725042409.124 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1457 2024-08-30T18:26:49.796Z,1725042409.796 [CTD_Seabird](INFO): Powering down 2024-08-30T18:26:49.808Z,1725042409.808 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:49.808Z,1725042409.808 [CTD_Seabird](INFO): Powering down 2024-08-30T18:26:49.825Z,1725042409.825 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:49.828Z,1725042409.828 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-08-30T18:26:49.828Z,1725042409.828 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:49.828Z,1725042409.828 [Radio_Surface](INFO): Join timeout helper Thread ID is 1458 2024-08-30T18:26:50.080Z,1725042410.080 [Radio_Surface](INFO): Powering down 2024-08-30T18:26:50.089Z,1725042410.089 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:50.090Z,1725042410.090 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:50.096Z,1725042410.096 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-08-30T18:26:50.096Z,1725042410.096 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:50.096Z,1725042410.096 [Onboard](INFO): Join timeout helper Thread ID is 1459 2024-08-30T18:26:50.844Z,1725042410.844 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-08-30T18:26:54.056Z,1725042414.056 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:54.056Z,1725042414.056 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:54.073Z,1725042414.073 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-08-30T18:26:54.073Z,1725042414.073 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:54.073Z,1725042414.073 [DataOverHttps](INFO): Join timeout helper Thread ID is 1460 2024-08-30T18:26:54.860Z,1725042414.860 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:54.860Z,1725042414.860 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:54.868Z,1725042414.868 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2024-08-30T18:26:54.869Z,1725042414.869 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:54.869Z,1725042414.869 [DATMMP](INFO): Join timeout helper Thread ID is 1461 2024-08-30T18:26:54.984Z,1725042414.984 [DATMMP](INFO): Stop 2024-08-30T18:26:54.984Z,1725042414.984 [DATMMP](INFO): uninitialize 2024-08-30T18:26:54.984Z,1725042414.984 [DATMMP](INFO): Powering down 2024-08-30T18:26:55.057Z,1725042415.057 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:55.057Z,1725042415.057 [DATMMP](INFO): uninitialize 2024-08-30T18:26:55.057Z,1725042415.057 [DATMMP](INFO): Powering down 2024-08-30T18:26:55.058Z,1725042415.058 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.069Z,1725042415.069 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-08-30T18:26:55.069Z,1725042415.069 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.069Z,1725042415.069 [logger](INFO): Join timeout helper Thread ID is 1462 2024-08-30T18:26:55.089Z,1725042415.089 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:55.089Z,1725042415.089 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.091Z,1725042415.091 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-08-30T18:26:55.091Z,1725042415.091 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.091Z,1725042415.091 [CommandLine](INFO): Join timeout helper Thread ID is 1463 2024-08-30T18:26:55.120Z,1725042415.120 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:55.120Z,1725042415.120 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.128Z,1725042415.128 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-08-30T18:26:55.129Z,1725042415.129 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.129Z,1725042415.129 [CommandExec](INFO): Join timeout helper Thread ID is 1464 2024-08-30T18:26:55.130Z,1725042415.130 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-08-30T18:26:55.130Z,1725042415.130 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.131Z,1725042415.131 [controlThread](INFO): Join timeout helper Thread ID is 1465 2024-08-30T18:26:55.372Z,1725042415.372 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-30T18:26:55.372Z,1725042415.372 [controlThread](DEBUG): Uninitializing ControlThread 2024-08-30T18:26:55.373Z,1725042415.373 [AHRS_M2](INFO): Powering down 2024-08-30T18:26:55.445Z,1725042415.445 [NAL9602](INFO): Powering down 2024-08-30T18:26:55.447Z,1725042415.447 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-08-30T18:26:55.448Z,1725042415.448 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-08-30T18:26:55.449Z,1725042415.449 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-08-30T18:26:55.449Z,1725042415.449 [MissionManager](INFO): Uninitializing Mission Default 2024-08-30T18:26:55.449Z,1725042415.449 [Default] Stopped 2024-08-30T18:26:55.449Z,1725042415.449 [Default](DEBUG): Aggregate::uninitialize Default 2024-08-30T18:26:55.449Z,1725042415.449 [Default:B.GoToSurface] Stopped 2024-08-30T18:26:55.449Z,1725042415.449 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-30T18:26:55.449Z,1725042415.449 [Default:CheckIn] Stopped 2024-08-30T18:26:55.450Z,1725042415.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-30T18:26:55.450Z,1725042415.450 [Default:CheckIn:Read_GPS] Stopped 2024-08-30T18:26:55.453Z,1725042415.453 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-08-30T18:26:55.453Z,1725042415.453 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-08-30T18:26:55.453Z,1725042415.453 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-08-30T18:26:55.453Z,1725042415.453 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-08-30T18:26:55.454Z,1725042415.454 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-08-30T18:26:55.454Z,1725042415.454 [BuoyancyServo](INFO): Powering down 2024-08-30T18:26:55.468Z,1725042415.468 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-08-30T18:26:55.468Z,1725042415.468 [ElevatorServo](INFO): Powering down 2024-08-30T18:26:55.469Z,1725042415.469 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-08-30T18:26:55.469Z,1725042415.469 [MassServo](INFO): Powering down 2024-08-30T18:26:55.470Z,1725042415.470 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-30T18:26:55.470Z,1725042415.470 [RudderServo](INFO): Powering down 2024-08-30T18:26:55.471Z,1725042415.471 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-08-30T18:26:55.471Z,1725042415.471 [ThrusterHE](INFO): Powering down 2024-08-30T18:26:55.472Z,1725042415.472 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-08-30T18:26:55.472Z,1725042415.472 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-08-30T18:26:55.472Z,1725042415.472 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-08-30T18:26:55.472Z,1725042415.472 [CBIT](DEBUG): Powering off loads. 2024-08-30T18:26:55.484Z,1725042415.484 [CBIT](DEBUG): Disabling WDT. 2024-08-30T18:26:55.496Z,1725042415.496 [CBIT](DEBUG): Opening all GF detection circuits. 2024-08-30T18:26:55.497Z,1725042415.497 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.553Z,1725042415.553 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.557Z,1725042415.557 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.563Z,1725042415.563 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.570Z,1725042415.570 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.644Z,1725042415.644 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.649Z,1725042415.649 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.691Z,1725042415.691 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-30T18:26:55.787Z,1725042415.787 [logger ThreadHandler](INFO): Thread cancelled.