2023-12-18T18:46:57.507Z,1702925217.507 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T18:46:57.508Z,1702925217.508 [Default:CheckIn:C.Wait] Stopped 2023-12-18T18:46:57.508Z,1702925217.508 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T18:46:57.508Z,1702925217.508 [Default:CheckIn:D] Running Loop=1 2023-12-18T18:46:57.933Z,1702925217.933 [Default:CheckIn:D] Stopped 2023-12-18T18:46:57.933Z,1702925217.933 [Default:CheckIn:E] Running Loop=1 2023-12-18T18:46:58.329Z,1702925218.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.491858 min 2023-12-18T18:46:58.329Z,1702925218.329 [Default:CheckIn:E] Stopped 2023-12-18T18:46:58.329Z,1702925218.329 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T18:46:58.329Z,1702925218.329 [Default:CheckIn] Stopped 2023-12-18T18:46:58.329Z,1702925218.329 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T18:46:58.330Z,1702925218.330 [Default:CheckIn](INFO): Running loop #10 2023-12-18T18:46:58.330Z,1702925218.330 [Default:CheckIn] Running Loop=10 2023-12-18T18:46:58.330Z,1702925218.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T18:46:58.330Z,1702925218.330 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T18:47:00.326Z,1702925220.326 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184703.00,A,3648.24775,N,12149.19841,W,20.138,95.67,181223,,,D*7E 2023-12-18T18:47:00.330Z,1702925220.330 [NAL9602](INFO): GPS fix at 20231218T184703: (36.804129, -121.819974) 2023-12-18T18:47:00.351Z,1702925220.351 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T18:47:00.351Z,1702925220.351 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T18:47:04.919Z,1702925224.919 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231216T164718/Courier0322.lzma 2023-12-18T18:47:08.934Z,1702925228.934 [DataOverHttps](INFO): Moved sent file to Logs/20231216T164718/Courier0322.lzma.bak 2023-12-18T18:47:08.934Z,1702925228.934 [DataOverHttps](INFO): SBD MOMSN=19119123 2023-12-18T18:47:24.684Z,1702925244.684 [NAL9602](INFO): SBD MO Status=0, MOMSN=35853, MT Status=0, MTMSN=0 2023-12-18T18:47:24.684Z,1702925244.684 [NAL9602](INFO): No messages in MT queue 2023-12-18T18:47:55.376Z,1702925275.376 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T18:48:09.180Z,1702925289.180 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247322 2023-12-18T18:48:28.976Z,1702925308.976 [NAL9602](INFO): SBD MO Status=1, MOMSN=35854, MT Status=0, MTMSN=0 2023-12-18T18:48:29.025Z,1702925309.025 [NAL9602](INFO): Sent 268 bytes from file Logs/20231218T184442/Courier0000.lzma 2023-12-18T18:48:29.025Z,1702925309.025 [NAL9602](INFO): Packets left to send: 0 2023-12-18T18:48:40.304Z,1702925320.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-12-18T18:48:50.080Z,1702925330.080 [NAL9602](INFO): SBD MO Status=2, MOMSN=35855, MT Status=2, MTMSN=0 2023-12-18T18:48:50.080Z,1702925330.080 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T18:49:15.444Z,1702925355.444 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-12-18T18:49:17.218Z,1702925357.218 [NAL9602](INFO): SBD MO Status=1, MOMSN=35855, MT Status=0, MTMSN=0 2023-12-18T18:49:17.272Z,1702925357.272 [NAL9602](INFO): Sent 39 bytes from file Logs/20231218T184442/Courier0003.lzma 2023-12-18T18:49:17.272Z,1702925357.272 [NAL9602](INFO): Packets left to send: 0 2023-12-18T18:49:28.044Z,1702925368.044 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002703 2023-12-18T18:49:33.478Z,1702925373.478 [NAL9602](INFO): SBD MO Status=2, MOMSN=35856, MT Status=2, MTMSN=0 2023-12-18T18:49:33.478Z,1702925373.478 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T18:49:36.114Z,1702925376.114 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20231216T164718/Express0323.lzma 2023-12-18T18:49:36.878Z,1702925376.878 [DataOverHttps](INFO): Moved sent file to Logs/20231216T164718/Express0323.lzma.bak 2023-12-18T18:49:36.878Z,1702925376.878 [DataOverHttps](INFO): SBD MOMSN=19119125 2023-12-18T18:50:04.201Z,1702925404.201 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T18:50:07.920Z,1702925407.920 [DataOverHttps](INFO): Sending 425 bytes from file Logs/20231218T184442/Express0001.lzma 2023-12-18T18:50:08.921Z,1702925408.921 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0001.lzma.bak 2023-12-18T18:50:08.922Z,1702925408.922 [DataOverHttps](INFO): SBD MOMSN=19119130 2023-12-18T18:50:25.099Z,1702925425.099 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231218T184442/Express0004.lzma 2023-12-18T18:50:26.102Z,1702925426.102 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0004.lzma.bak 2023-12-18T18:50:26.102Z,1702925426.102 [DataOverHttps](INFO): SBD MOMSN=19119134 2023-12-18T18:50:27.645Z,1702925427.645 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T18:50:27.645Z,1702925427.645 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T18:50:27.645Z,1702925427.645 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T18:55:28.219Z,1702925728.219 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T18:55:28.220Z,1702925728.220 [Default:CheckIn:C.Wait] Stopped 2023-12-18T18:55:28.220Z,1702925728.220 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T18:55:28.220Z,1702925728.220 [Default:CheckIn:D] Running Loop=1 2023-12-18T18:55:28.624Z,1702925728.624 [Default:CheckIn:D] Stopped 2023-12-18T18:55:28.624Z,1702925728.624 [Default:CheckIn:E] Running Loop=1 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.003381 min 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn:E] Stopped 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn] Stopped 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn](INFO): Running loop #11 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn] Running Loop=11 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T18:55:29.029Z,1702925729.029 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T18:55:31.033Z,1702925731.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185534.00,A,3648.26679,N,12147.14491,W,4.102,193.04,181223,,,D*79 2023-12-18T18:55:31.036Z,1702925731.036 [NAL9602](INFO): GPS fix at 20231218T185534: (36.804446, -121.785748) 2023-12-18T18:55:31.067Z,1702925731.067 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T18:55:31.067Z,1702925731.067 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T18:55:38.641Z,1702925738.641 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231218T184442/Courier0006.lzma 2023-12-18T18:55:39.641Z,1702925739.641 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0006.lzma.bak 2023-12-18T18:55:39.642Z,1702925739.642 [DataOverHttps](INFO): SBD MOMSN=19119183 2023-12-18T18:55:52.864Z,1702925752.864 [NAL9602](INFO): SBD MO Status=2, MOMSN=35856, MT Status=2, MTMSN=0 2023-12-18T18:55:52.864Z,1702925752.864 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T18:55:56.991Z,1702925756.991 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231218T184442/Express0007.lzma 2023-12-18T18:55:57.993Z,1702925757.993 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0007.lzma.bak 2023-12-18T18:55:57.994Z,1702925757.994 [DataOverHttps](INFO): SBD MOMSN=19119186 2023-12-18T18:55:59.335Z,1702925759.335 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T18:55:59.335Z,1702925759.335 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T18:55:59.336Z,1702925759.336 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T18:56:29.214Z,1702925789.214 [NAL9602](INFO): SBD MO Status=2, MOMSN=35856, MT Status=2, MTMSN=0 2023-12-18T18:56:29.214Z,1702925789.214 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T18:56:56.704Z,1702925816.704 [NAL9602](INFO): SBD MO Status=1, MOMSN=35856, MT Status=0, MTMSN=0 2023-12-18T18:56:56.704Z,1702925816.704 [NAL9602](INFO): No messages in MT queue 2023-12-18T18:57:27.383Z,1702925847.383 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:00:59.952Z,1702926059.952 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:00:59.952Z,1702926059.952 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:00:59.952Z,1702926059.952 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:00:59.953Z,1702926059.953 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:01:00.315Z,1702926060.315 [Default:CheckIn:D] Stopped 2023-12-18T19:01:00.316Z,1702926060.316 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:01:00.721Z,1702926060.721 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.531571 min 2023-12-18T19:01:00.721Z,1702926060.721 [Default:CheckIn:E] Stopped 2023-12-18T19:01:00.721Z,1702926060.721 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:01:00.722Z,1702926060.722 [Default:CheckIn] Stopped 2023-12-18T19:01:00.722Z,1702926060.722 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:01:00.722Z,1702926060.722 [Default:CheckIn](INFO): Running loop #12 2023-12-18T19:01:00.722Z,1702926060.722 [Default:CheckIn] Running Loop=12 2023-12-18T19:01:00.722Z,1702926060.722 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:01:00.722Z,1702926060.722 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:01:02.726Z,1702926062.726 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190105.00,A,3648.14815,N,12147.21960,W,0.156,218.01,181223,,,D*7B 2023-12-18T19:01:02.729Z,1702926062.729 [NAL9602](INFO): GPS fix at 20231218T190105: (36.802469, -121.786993) 2023-12-18T19:01:02.743Z,1702926062.743 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:01:02.743Z,1702926062.743 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:01:11.330Z,1702926071.330 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231218T184442/Courier0009.lzma 2023-12-18T19:01:11.703Z,1702926071.703 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0009.lzma.bak 2023-12-18T19:01:11.703Z,1702926071.703 [DataOverHttps](INFO): SBD MOMSN=19119202 2023-12-18T19:01:28.127Z,1702926088.127 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20231218T184442/Express0010.lzma 2023-12-18T19:01:29.130Z,1702926089.130 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0010.lzma.bak 2023-12-18T19:01:29.130Z,1702926089.130 [DataOverHttps](INFO): SBD MOMSN=19119207 2023-12-18T19:01:30.661Z,1702926090.661 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:01:30.661Z,1702926090.661 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:01:30.661Z,1702926090.661 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:01:57.267Z,1702926117.267 [NAL9602](INFO): SBD MO Status=2, MOMSN=35857, MT Status=2, MTMSN=0 2023-12-18T19:01:57.267Z,1702926117.267 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:02:27.568Z,1702926147.568 [NAL9602](INFO): SBD MO Status=1, MOMSN=35857, MT Status=0, MTMSN=0 2023-12-18T19:02:27.568Z,1702926147.568 [NAL9602](INFO): No messages in MT queue 2023-12-18T19:02:58.289Z,1702926178.289 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:06:31.208Z,1702926391.208 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:06:31.208Z,1702926391.208 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:06:31.209Z,1702926391.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:06:31.209Z,1702926391.209 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:06:31.616Z,1702926391.616 [Default:CheckIn:D] Stopped 2023-12-18T19:06:31.616Z,1702926391.616 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:06:32.047Z,1702926392.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.053255 min 2023-12-18T19:06:32.047Z,1702926392.047 [Default:CheckIn:E] Stopped 2023-12-18T19:06:32.048Z,1702926392.048 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:06:32.048Z,1702926392.048 [Default:CheckIn] Stopped 2023-12-18T19:06:32.048Z,1702926392.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:06:32.048Z,1702926392.048 [Default:CheckIn](INFO): Running loop #13 2023-12-18T19:06:32.048Z,1702926392.048 [Default:CheckIn] Running Loop=13 2023-12-18T19:06:32.048Z,1702926392.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:06:32.048Z,1702926392.048 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:06:34.026Z,1702926394.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190637.00,A,3648.14276,N,12147.22866,W,0.097,205.62,181223,,,D*73 2023-12-18T19:06:34.028Z,1702926394.028 [NAL9602](INFO): GPS fix at 20231218T190637: (36.802379, -121.787144) 2023-12-18T19:06:34.091Z,1702926394.091 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:06:34.091Z,1702926394.091 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:06:42.035Z,1702926402.035 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231218T184442/Courier0012.lzma 2023-12-18T19:06:43.037Z,1702926403.037 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0012.lzma.bak 2023-12-18T19:06:43.038Z,1702926403.038 [DataOverHttps](INFO): SBD MOMSN=19119280 2023-12-18T19:06:48.974Z,1702926408.974 [NAL9602](INFO): SBD MO Status=1, MOMSN=35858, MT Status=0, MTMSN=0 2023-12-18T19:06:48.974Z,1702926408.974 [NAL9602](INFO): No messages in MT queue 2023-12-18T19:06:59.436Z,1702926419.436 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20231218T184442/Express0013.lzma 2023-12-18T19:07:00.438Z,1702926420.438 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0013.lzma.bak 2023-12-18T19:07:00.438Z,1702926420.438 [DataOverHttps](INFO): SBD MOMSN=19119283 2023-12-18T19:07:01.936Z,1702926421.936 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:07:01.936Z,1702926421.936 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:07:01.937Z,1702926421.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:07:19.676Z,1702926439.676 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:12:02.492Z,1702926722.492 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:12:02.492Z,1702926722.492 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:12:02.492Z,1702926722.492 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:12:02.492Z,1702926722.492 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:12:02.905Z,1702926722.905 [Default:CheckIn:D] Stopped 2023-12-18T19:12:02.905Z,1702926722.905 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:12:03.316Z,1702926723.316 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.574723 min 2023-12-18T19:12:03.316Z,1702926723.316 [Default:CheckIn:E] Stopped 2023-12-18T19:12:03.316Z,1702926723.316 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:12:03.316Z,1702926723.316 [Default:CheckIn] Stopped 2023-12-18T19:12:03.316Z,1702926723.316 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:12:03.317Z,1702926723.317 [Default:CheckIn](INFO): Running loop #14 2023-12-18T19:12:03.317Z,1702926723.317 [Default:CheckIn] Running Loop=14 2023-12-18T19:12:03.317Z,1702926723.317 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:12:03.317Z,1702926723.317 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:12:05.311Z,1702926725.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191208.00,A,3648.14670,N,12147.25444,W,0.350,290.72,181223,,,D*76 2023-12-18T19:12:05.313Z,1702926725.313 [NAL9602](INFO): GPS fix at 20231218T191208: (36.802445, -121.787574) 2023-12-18T19:12:05.325Z,1702926725.325 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:12:05.325Z,1702926725.325 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:12:13.327Z,1702926733.327 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231218T184442/Courier0015.lzma 2023-12-18T19:12:14.329Z,1702926734.329 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0015.lzma.bak 2023-12-18T19:12:14.330Z,1702926734.330 [DataOverHttps](INFO): SBD MOMSN=19119289 2023-12-18T19:12:30.707Z,1702926750.707 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20231218T184442/Express0016.lzma 2023-12-18T19:12:31.712Z,1702926751.712 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0016.lzma.bak 2023-12-18T19:12:31.712Z,1702926751.712 [DataOverHttps](INFO): SBD MOMSN=19119292 2023-12-18T19:12:33.197Z,1702926753.197 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:12:33.197Z,1702926753.197 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:12:33.197Z,1702926753.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:13:19.646Z,1702926799.646 [NAL9602](INFO): SBD MO Status=2, MOMSN=35859, MT Status=2, MTMSN=0 2023-12-18T19:13:19.647Z,1702926799.647 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:13:51.162Z,1702926831.162 [NAL9602](INFO): SBD MO Status=1, MOMSN=35859, MT Status=0, MTMSN=0 2023-12-18T19:13:51.163Z,1702926831.163 [NAL9602](INFO): No messages in MT queue 2023-12-18T19:14:21.861Z,1702926861.861 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:16:50.140Z,1702927010.140 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.00, 20.00,2171.90 2023-12-18T19:17:33.826Z,1702927053.826 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:17:33.827Z,1702927053.827 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:17:33.827Z,1702927053.827 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:17:33.827Z,1702927053.827 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:17:34.185Z,1702927054.185 [Default:CheckIn:D] Stopped 2023-12-18T19:17:34.185Z,1702927054.185 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:17:34.581Z,1702927054.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.096061 min 2023-12-18T19:17:34.581Z,1702927054.581 [Default:CheckIn:E] Stopped 2023-12-18T19:17:34.581Z,1702927054.581 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:17:34.581Z,1702927054.581 [Default:CheckIn] Stopped 2023-12-18T19:17:34.581Z,1702927054.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:17:34.581Z,1702927054.581 [Default:CheckIn](INFO): Running loop #15 2023-12-18T19:17:34.582Z,1702927054.582 [Default:CheckIn] Running Loop=15 2023-12-18T19:17:34.582Z,1702927054.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:17:34.582Z,1702927054.582 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:17:36.590Z,1702927056.590 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191739.00,A,3648.13964,N,12147.25208,W,0.194,290.72,181223,,,D*78 2023-12-18T19:17:36.593Z,1702927056.593 [NAL9602](INFO): GPS fix at 20231218T191739: (36.802327, -121.787535) 2023-12-18T19:17:36.604Z,1702927056.604 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:17:36.604Z,1702927056.604 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:17:44.467Z,1702927064.467 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231218T184442/Courier0018.lzma 2023-12-18T19:17:45.470Z,1702927065.470 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0018.lzma.bak 2023-12-18T19:17:45.470Z,1702927065.470 [DataOverHttps](INFO): SBD MOMSN=19119303 2023-12-18T19:18:01.915Z,1702927081.915 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20231218T184442/Express0019.lzma 2023-12-18T19:18:02.918Z,1702927082.918 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0019.lzma.bak 2023-12-18T19:18:02.918Z,1702927082.918 [DataOverHttps](INFO): SBD MOMSN=19119306 2023-12-18T19:18:04.468Z,1702927084.468 [NAL9602](INFO): SBD MO Status=2, MOMSN=35860, MT Status=2, MTMSN=0 2023-12-18T19:18:04.468Z,1702927084.468 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:18:04.481Z,1702927084.481 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:18:04.481Z,1702927084.481 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:18:04.481Z,1702927084.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:18:44.458Z,1702927124.458 [NAL9602](INFO): SBD MO Status=2, MOMSN=35860, MT Status=2, MTMSN=0 2023-12-18T19:18:44.458Z,1702927124.458 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:22:42.630Z,1702927362.630 [NAL9602](INFO): SBD MO Status=2, MOMSN=35860, MT Status=2, MTMSN=0 2023-12-18T19:22:42.631Z,1702927362.631 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:22:43.030Z,1702927363.030 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-12-18T19:23:05.259Z,1702927385.259 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:23:05.259Z,1702927385.259 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:23:05.259Z,1702927385.259 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:23:05.259Z,1702927385.259 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:23:05.684Z,1702927385.684 [Default:CheckIn:D] Stopped 2023-12-18T19:23:05.684Z,1702927385.684 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:23:06.081Z,1702927386.081 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.621045 min 2023-12-18T19:23:06.081Z,1702927386.081 [Default:CheckIn:E] Stopped 2023-12-18T19:23:06.081Z,1702927386.081 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:23:06.081Z,1702927386.081 [Default:CheckIn] Stopped 2023-12-18T19:23:06.081Z,1702927386.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:23:06.081Z,1702927386.081 [Default:CheckIn](INFO): Running loop #16 2023-12-18T19:23:06.081Z,1702927386.081 [Default:CheckIn] Running Loop=16 2023-12-18T19:23:06.081Z,1702927386.081 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:23:06.082Z,1702927386.082 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:23:08.083Z,1702927388.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192307.00,A,3648.14559,N,12147.26629,W,0.156,346.51,181223,,,A*73 2023-12-18T19:23:08.085Z,1702927388.085 [NAL9602](INFO): GPS fix at 20231218T192307: (36.802427, -121.787772) 2023-12-18T19:23:08.117Z,1702927388.117 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:23:08.117Z,1702927388.117 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:23:15.431Z,1702927395.431 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231218T184442/Courier0021.lzma 2023-12-18T19:23:16.434Z,1702927396.434 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0021.lzma.bak 2023-12-18T19:23:16.434Z,1702927396.434 [DataOverHttps](INFO): SBD MOMSN=19119309 2023-12-18T19:23:33.160Z,1702927413.160 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20231218T184442/Express0022.lzma 2023-12-18T19:23:34.162Z,1702927414.162 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0022.lzma.bak 2023-12-18T19:23:34.162Z,1702927414.162 [DataOverHttps](INFO): SBD MOMSN=19119312 2023-12-18T19:23:35.625Z,1702927415.625 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:23:35.625Z,1702927415.625 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:23:35.625Z,1702927415.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:23:40.401Z,1702927420.401 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:28:36.156Z,1702927716.156 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:28:36.156Z,1702927716.156 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:28:36.156Z,1702927716.156 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:28:36.156Z,1702927716.156 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:28:36.552Z,1702927716.552 [Default:CheckIn:D] Stopped 2023-12-18T19:28:36.552Z,1702927716.552 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:28:36.944Z,1702927716.944 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.135514 min 2023-12-18T19:28:36.944Z,1702927716.944 [Default:CheckIn:E] Stopped 2023-12-18T19:28:36.944Z,1702927716.944 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:28:36.944Z,1702927716.944 [Default:CheckIn] Stopped 2023-12-18T19:28:36.944Z,1702927716.944 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:28:36.944Z,1702927716.944 [Default:CheckIn](INFO): Running loop #17 2023-12-18T19:28:36.945Z,1702927716.945 [Default:CheckIn] Running Loop=17 2023-12-18T19:28:36.945Z,1702927716.945 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:28:36.945Z,1702927716.945 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:28:38.960Z,1702927718.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192838.00,A,3648.14256,N,12147.25768,W,0.700,18.22,181223,,,A*42 2023-12-18T19:28:38.963Z,1702927718.963 [NAL9602](INFO): GPS fix at 20231218T192838: (36.802376, -121.787628) 2023-12-18T19:28:38.974Z,1702927718.974 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:28:38.974Z,1702927718.974 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:28:46.615Z,1702927726.615 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231218T184442/Courier0024.lzma 2023-12-18T19:28:47.618Z,1702927727.618 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0024.lzma.bak 2023-12-18T19:28:47.618Z,1702927727.618 [DataOverHttps](INFO): SBD MOMSN=19119323 2023-12-18T19:29:04.279Z,1702927744.279 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20231218T184442/Express0025.lzma 2023-12-18T19:29:05.278Z,1702927745.278 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0025.lzma.bak 2023-12-18T19:29:05.278Z,1702927745.278 [DataOverHttps](INFO): SBD MOMSN=19119326 2023-12-18T19:29:06.849Z,1702927746.849 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:29:06.849Z,1702927746.849 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:29:06.849Z,1702927746.849 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:30:36.527Z,1702927836.527 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-12-18T19:30:36.529Z,1702927836.529 [BPC1](INFO): Received data from all battery sticks. 2023-12-18T19:32:27.623Z,1702927947.623 [NAL9602](INFO): SBD MO Status=2, MOMSN=35860, MT Status=2, MTMSN=0 2023-12-18T19:32:27.623Z,1702927947.623 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:32:43.391Z,1702927963.391 [NAL9602](INFO): SBD MO Status=2, MOMSN=35860, MT Status=2, MTMSN=0 2023-12-18T19:32:43.391Z,1702927963.391 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:33:22.567Z,1702928002.567 [NAL9602](INFO): SBD MO Status=2, MOMSN=35860, MT Status=2, MTMSN=0 2023-12-18T19:33:22.567Z,1702928002.567 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:33:49.634Z,1702928029.634 [NAL9602](INFO): SBD MO Status=1, MOMSN=35860, MT Status=0, MTMSN=0 2023-12-18T19:33:49.635Z,1702928029.635 [NAL9602](INFO): No messages in MT queue 2023-12-18T19:34:07.461Z,1702928047.461 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:34:07.461Z,1702928047.461 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:34:07.461Z,1702928047.461 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:34:07.462Z,1702928047.462 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:34:07.852Z,1702928047.852 [Default:CheckIn:D] Stopped 2023-12-18T19:34:07.852Z,1702928047.852 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.657178 min 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn:E] Stopped 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn] Stopped 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn](INFO): Running loop #18 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn] Running Loop=18 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:34:08.238Z,1702928048.238 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:34:10.243Z,1702928050.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193409.00,A,3648.14239,N,12147.25547,W,0.408,18.22,181223,,,A*40 2023-12-18T19:34:10.245Z,1702928050.245 [NAL9602](INFO): GPS fix at 20231218T193409: (36.802373, -121.787591) 2023-12-18T19:34:10.273Z,1702928050.273 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:34:10.273Z,1702928050.273 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:34:18.352Z,1702928058.352 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20231218T184442/Courier0027.lzma 2023-12-18T19:34:19.354Z,1702928059.354 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0027.lzma.bak 2023-12-18T19:34:19.354Z,1702928059.354 [DataOverHttps](INFO): SBD MOMSN=19119333 2023-12-18T19:34:35.708Z,1702928075.708 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20231218T184442/Express0028.lzma 2023-12-18T19:34:36.710Z,1702928076.710 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0028.lzma.bak 2023-12-18T19:34:36.710Z,1702928076.710 [DataOverHttps](INFO): SBD MOMSN=19119340 2023-12-18T19:34:38.136Z,1702928078.136 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:34:38.136Z,1702928078.136 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:34:38.137Z,1702928078.137 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:34:42.562Z,1702928082.562 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:37:37.914Z,1702928257.914 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +0.00, +0.00 +0.00, 0.00,4261.63 2023-12-18T19:39:38.704Z,1702928378.704 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:39:38.704Z,1702928378.704 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:39:38.704Z,1702928378.704 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:39:38.705Z,1702928378.705 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:39:39.114Z,1702928379.114 [Default:CheckIn:D] Stopped 2023-12-18T19:39:39.114Z,1702928379.114 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:39:39.540Z,1702928379.540 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.178215 min 2023-12-18T19:39:39.540Z,1702928379.540 [Default:CheckIn:E] Stopped 2023-12-18T19:39:39.541Z,1702928379.541 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:39:39.541Z,1702928379.541 [Default:CheckIn] Stopped 2023-12-18T19:39:39.541Z,1702928379.541 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:39:39.542Z,1702928379.542 [Default:CheckIn](INFO): Running loop #19 2023-12-18T19:39:39.542Z,1702928379.542 [Default:CheckIn] Running Loop=19 2023-12-18T19:39:39.542Z,1702928379.542 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:39:39.542Z,1702928379.542 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:39:41.532Z,1702928381.532 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193940.00,A,3648.13988,N,12147.25377,W,0.369,0.00,181223,,,A*7A 2023-12-18T19:39:41.538Z,1702928381.538 [NAL9602](INFO): GPS fix at 20231218T193940: (36.802331, -121.787563) 2023-12-18T19:39:41.601Z,1702928381.601 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:39:41.602Z,1702928381.602 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:39:49.569Z,1702928389.569 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231218T184442/Courier0030.lzma 2023-12-18T19:39:50.566Z,1702928390.566 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0030.lzma.bak 2023-12-18T19:39:50.566Z,1702928390.566 [DataOverHttps](INFO): SBD MOMSN=19119345 2023-12-18T19:40:06.941Z,1702928406.941 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20231218T184442/Express0031.lzma 2023-12-18T19:40:07.942Z,1702928407.942 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0031.lzma.bak 2023-12-18T19:40:07.942Z,1702928407.942 [DataOverHttps](INFO): SBD MOMSN=19119348 2023-12-18T19:40:09.418Z,1702928409.418 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:40:09.418Z,1702928409.418 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:40:09.418Z,1702928409.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:40:28.790Z,1702928428.790 [NAL9602](INFO): SBD MO Status=1, MOMSN=35861, MT Status=0, MTMSN=0 2023-12-18T19:40:28.791Z,1702928428.791 [NAL9602](INFO): No messages in MT queue 2023-12-18T19:40:59.489Z,1702928459.489 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:45:09.984Z,1702928709.984 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:45:09.984Z,1702928709.984 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:45:09.984Z,1702928709.984 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:45:09.984Z,1702928709.984 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:45:10.396Z,1702928710.396 [Default:CheckIn:D] Stopped 2023-12-18T19:45:10.396Z,1702928710.396 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:45:10.787Z,1702928710.787 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.699577 min 2023-12-18T19:45:10.787Z,1702928710.787 [Default:CheckIn:E] Stopped 2023-12-18T19:45:10.787Z,1702928710.787 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:45:10.787Z,1702928710.787 [Default:CheckIn] Stopped 2023-12-18T19:45:10.787Z,1702928710.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:45:10.787Z,1702928710.787 [Default:CheckIn](INFO): Running loop #20 2023-12-18T19:45:10.787Z,1702928710.787 [Default:CheckIn] Running Loop=20 2023-12-18T19:45:10.787Z,1702928710.787 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:45:10.788Z,1702928710.788 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:45:12.803Z,1702928712.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194512.00,A,3648.14531,N,12147.25690,W,0.369,0.00,181223,,,A*73 2023-12-18T19:45:12.805Z,1702928712.805 [NAL9602](INFO): GPS fix at 20231218T194512: (36.802422, -121.787615) 2023-12-18T19:45:12.817Z,1702928712.817 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:45:12.817Z,1702928712.817 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:45:20.847Z,1702928720.847 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231218T184442/Courier0033.lzma 2023-12-18T19:45:21.850Z,1702928721.850 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0033.lzma.bak 2023-12-18T19:45:21.850Z,1702928721.850 [DataOverHttps](INFO): SBD MOMSN=19119359 2023-12-18T19:45:38.224Z,1702928738.224 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20231218T184442/Express0034.lzma 2023-12-18T19:45:39.226Z,1702928739.226 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0034.lzma.bak 2023-12-18T19:45:39.226Z,1702928739.226 [DataOverHttps](INFO): SBD MOMSN=19119362 2023-12-18T19:45:40.694Z,1702928740.694 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:45:40.694Z,1702928740.694 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:45:40.694Z,1702928740.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:47:15.210Z,1702928835.210 [NAL9602](INFO): SBD MO Status=2, MOMSN=35862, MT Status=2, MTMSN=0 2023-12-18T19:47:15.210Z,1702928835.210 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:47:51.597Z,1702928871.597 [NAL9602](INFO): SBD MO Status=2, MOMSN=35862, MT Status=2, MTMSN=0 2023-12-18T19:47:51.597Z,1702928871.597 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:49:24.899Z,1702928964.899 [NAL9602](INFO): SBD MO Status=1, MOMSN=35862, MT Status=0, MTMSN=0 2023-12-18T19:49:24.899Z,1702928964.899 [NAL9602](INFO): No messages in MT queue 2023-12-18T19:49:55.617Z,1702928995.617 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:50:41.278Z,1702929041.278 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:50:41.278Z,1702929041.278 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:50:41.278Z,1702929041.278 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:50:41.279Z,1702929041.279 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:50:41.694Z,1702929041.694 [Default:CheckIn:D] Stopped 2023-12-18T19:50:41.694Z,1702929041.694 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:50:42.072Z,1702929042.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.221224 min 2023-12-18T19:50:42.073Z,1702929042.073 [Default:CheckIn:E] Stopped 2023-12-18T19:50:42.073Z,1702929042.073 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:50:42.073Z,1702929042.073 [Default:CheckIn] Stopped 2023-12-18T19:50:42.073Z,1702929042.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:50:42.073Z,1702929042.073 [Default:CheckIn](INFO): Running loop #21 2023-12-18T19:50:42.073Z,1702929042.073 [Default:CheckIn] Running Loop=21 2023-12-18T19:50:42.073Z,1702929042.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:50:42.073Z,1702929042.073 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:50:44.083Z,1702929044.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195043.00,A,3648.17411,N,12147.27618,W,0.019,290.82,181223,,,A*74 2023-12-18T19:50:44.085Z,1702929044.085 [NAL9602](INFO): GPS fix at 20231218T195043: (36.802902, -121.787936) 2023-12-18T19:50:44.097Z,1702929044.097 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:50:44.097Z,1702929044.097 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:50:52.223Z,1702929052.223 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231218T184442/Courier0036.lzma 2023-12-18T19:50:53.226Z,1702929053.226 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0036.lzma.bak 2023-12-18T19:50:53.226Z,1702929053.226 [DataOverHttps](INFO): SBD MOMSN=19119365 2023-12-18T19:51:06.303Z,1702929066.303 [NAL9602](INFO): SBD MO Status=2, MOMSN=35863, MT Status=2, MTMSN=0 2023-12-18T19:51:06.303Z,1702929066.303 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:51:09.559Z,1702929069.559 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20231218T184442/Express0037.lzma 2023-12-18T19:51:10.554Z,1702929070.554 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0037.lzma.bak 2023-12-18T19:51:10.554Z,1702929070.554 [DataOverHttps](INFO): SBD MOMSN=19119368 2023-12-18T19:51:11.968Z,1702929071.968 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:51:11.969Z,1702929071.969 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:51:11.969Z,1702929071.969 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:51:30.943Z,1702929090.943 [NAL9602](INFO): SBD MO Status=2, MOMSN=35863, MT Status=2, MTMSN=0 2023-12-18T19:51:30.943Z,1702929090.943 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T19:52:59.428Z,1702929179.428 [NAL9602](INFO): SBD MO Status=1, MOMSN=35863, MT Status=0, MTMSN=0 2023-12-18T19:52:59.428Z,1702929179.428 [NAL9602](INFO): No messages in MT queue 2023-12-18T19:53:30.125Z,1702929210.125 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T19:56:12.539Z,1702929372.539 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T19:56:12.539Z,1702929372.539 [Default:CheckIn:C.Wait] Stopped 2023-12-18T19:56:12.539Z,1702929372.539 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T19:56:12.540Z,1702929372.540 [Default:CheckIn:D] Running Loop=1 2023-12-18T19:56:12.945Z,1702929372.945 [Default:CheckIn:D] Stopped 2023-12-18T19:56:12.945Z,1702929372.945 [Default:CheckIn:E] Running Loop=1 2023-12-18T19:56:13.361Z,1702929373.361 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.742065 min 2023-12-18T19:56:13.361Z,1702929373.361 [Default:CheckIn:E] Stopped 2023-12-18T19:56:13.361Z,1702929373.361 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T19:56:13.361Z,1702929373.361 [Default:CheckIn] Stopped 2023-12-18T19:56:13.361Z,1702929373.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T19:56:13.361Z,1702929373.361 [Default:CheckIn](INFO): Running loop #22 2023-12-18T19:56:13.361Z,1702929373.361 [Default:CheckIn] Running Loop=22 2023-12-18T19:56:13.361Z,1702929373.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T19:56:13.362Z,1702929373.362 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T19:56:15.363Z,1702929375.363 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195614.00,A,3648.17660,N,12147.27521,W,0.058,333.05,181223,,,A*7F 2023-12-18T19:56:15.375Z,1702929375.375 [NAL9602](INFO): GPS fix at 20231218T195614: (36.802943, -121.787920) 2023-12-18T19:56:15.386Z,1702929375.386 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T19:56:15.386Z,1702929375.386 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T19:56:22.631Z,1702929382.631 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231218T184442/Courier0039.lzma 2023-12-18T19:56:23.634Z,1702929383.634 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0039.lzma.bak 2023-12-18T19:56:23.634Z,1702929383.634 [DataOverHttps](INFO): SBD MOMSN=19119390 2023-12-18T19:56:40.052Z,1702929400.052 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20231218T184442/Express0040.lzma 2023-12-18T19:56:41.054Z,1702929401.054 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0040.lzma.bak 2023-12-18T19:56:41.054Z,1702929401.054 [DataOverHttps](INFO): SBD MOMSN=19119393 2023-12-18T19:56:42.481Z,1702929402.481 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T19:56:42.481Z,1702929402.481 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T19:56:42.481Z,1702929402.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T19:57:04.267Z,1702929424.267 [NAL9602](INFO): SBD MO Status=1, MOMSN=35864, MT Status=0, MTMSN=0 2023-12-18T19:57:04.267Z,1702929424.267 [NAL9602](INFO): No messages in MT queue 2023-12-18T19:57:34.970Z,1702929454.970 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T20:01:43.039Z,1702929703.039 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T20:01:43.039Z,1702929703.039 [Default:CheckIn:C.Wait] Stopped 2023-12-18T20:01:43.039Z,1702929703.039 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T20:01:43.039Z,1702929703.039 [Default:CheckIn:D] Running Loop=1 2023-12-18T20:01:43.448Z,1702929703.448 [Default:CheckIn:D] Stopped 2023-12-18T20:01:43.448Z,1702929703.448 [Default:CheckIn:E] Running Loop=1 2023-12-18T20:01:43.922Z,1702929703.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.250439 min 2023-12-18T20:01:43.922Z,1702929703.922 [Default:CheckIn:E] Stopped 2023-12-18T20:01:43.922Z,1702929703.922 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T20:01:43.922Z,1702929703.922 [Default:CheckIn] Stopped 2023-12-18T20:01:43.922Z,1702929703.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T20:01:43.922Z,1702929703.922 [Default:CheckIn](INFO): Running loop #23 2023-12-18T20:01:43.922Z,1702929703.922 [Default:CheckIn] Running Loop=23 2023-12-18T20:01:43.923Z,1702929703.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T20:01:43.923Z,1702929703.923 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T20:01:45.863Z,1702929705.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200145.00,A,3648.17098,N,12147.28042,W,0.097,333.05,181223,,,A*7E 2023-12-18T20:01:45.865Z,1702929705.865 [NAL9602](INFO): GPS fix at 20231218T200145: (36.802850, -121.788007) 2023-12-18T20:01:45.916Z,1702929705.916 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T20:01:45.916Z,1702929705.916 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T20:01:54.004Z,1702929714.004 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231218T184442/Courier0042.lzma 2023-12-18T20:01:55.007Z,1702929715.007 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0042.lzma.bak 2023-12-18T20:01:55.007Z,1702929715.007 [DataOverHttps](INFO): SBD MOMSN=19119419 2023-12-18T20:02:11.276Z,1702929731.276 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20231218T184442/Express0043.lzma 2023-12-18T20:02:12.279Z,1702929732.279 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0043.lzma.bak 2023-12-18T20:02:12.279Z,1702929732.279 [DataOverHttps](INFO): SBD MOMSN=19119422 2023-12-18T20:02:13.777Z,1702929733.777 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T20:02:13.777Z,1702929733.777 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T20:02:13.777Z,1702929733.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T20:03:35.759Z,1702929815.759 [NAL9602](INFO): SBD MO Status=2, MOMSN=35865, MT Status=2, MTMSN=0 2023-12-18T20:03:35.759Z,1702929815.759 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T20:04:54.535Z,1702929894.535 [NAL9602](INFO): SBD MO Status=2, MOMSN=35865, MT Status=2, MTMSN=0 2023-12-18T20:04:54.535Z,1702929894.535 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T20:05:05.037Z,1702929905.037 [NAL9602](INFO): SBD MO Status=1, MOMSN=35865, MT Status=0, MTMSN=0 2023-12-18T20:05:05.038Z,1702929905.038 [NAL9602](INFO): No messages in MT queue 2023-12-18T20:05:35.745Z,1702929935.745 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T20:07:14.337Z,1702930034.337 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T20:07:14.337Z,1702930034.337 [Default:CheckIn:C.Wait] Stopped 2023-12-18T20:07:14.337Z,1702930034.337 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T20:07:14.338Z,1702930034.338 [Default:CheckIn:D] Running Loop=1 2023-12-18T20:07:14.754Z,1702930034.754 [Default:CheckIn:D] Stopped 2023-12-18T20:07:14.755Z,1702930034.755 [Default:CheckIn:E] Running Loop=1 2023-12-18T20:07:15.140Z,1702930035.140 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.772225 min 2023-12-18T20:07:15.140Z,1702930035.140 [Default:CheckIn:E] Stopped 2023-12-18T20:07:15.140Z,1702930035.140 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T20:07:15.140Z,1702930035.140 [Default:CheckIn] Stopped 2023-12-18T20:07:15.141Z,1702930035.141 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T20:07:15.141Z,1702930035.141 [Default:CheckIn](INFO): Running loop #24 2023-12-18T20:07:15.141Z,1702930035.141 [Default:CheckIn] Running Loop=24 2023-12-18T20:07:15.141Z,1702930035.141 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T20:07:15.141Z,1702930035.141 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T20:07:17.147Z,1702930037.147 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200716.00,A,3648.17682,N,12147.27719,W,0.369,333.05,181223,,,A*77 2023-12-18T20:07:17.149Z,1702930037.149 [NAL9602](INFO): GPS fix at 20231218T200716: (36.802947, -121.787953) 2023-12-18T20:07:17.161Z,1702930037.161 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T20:07:17.161Z,1702930037.161 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T20:07:25.526Z,1702930045.526 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231218T184442/Courier0045.lzma 2023-12-18T20:07:26.522Z,1702930046.522 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0045.lzma.bak 2023-12-18T20:07:26.522Z,1702930046.522 [DataOverHttps](INFO): SBD MOMSN=19119438 2023-12-18T20:07:42.936Z,1702930062.936 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20231218T184442/Express0046.lzma 2023-12-18T20:07:43.938Z,1702930063.938 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0046.lzma.bak 2023-12-18T20:07:43.938Z,1702930063.938 [DataOverHttps](INFO): SBD MOMSN=19119442 2023-12-18T20:07:45.432Z,1702930065.432 [NAL9602](INFO): SBD MO Status=1, MOMSN=35866, MT Status=0, MTMSN=0 2023-12-18T20:07:45.432Z,1702930065.432 [NAL9602](INFO): No messages in MT queue 2023-12-18T20:07:45.449Z,1702930065.449 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T20:07:45.449Z,1702930065.449 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T20:07:45.449Z,1702930065.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T20:08:16.133Z,1702930096.133 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T20:12:46.015Z,1702930366.015 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T20:12:46.016Z,1702930366.016 [Default:CheckIn:C.Wait] Stopped 2023-12-18T20:12:46.016Z,1702930366.016 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T20:12:46.016Z,1702930366.016 [Default:CheckIn:D] Running Loop=1 2023-12-18T20:12:46.425Z,1702930366.425 [Default:CheckIn:D] Stopped 2023-12-18T20:12:46.425Z,1702930366.425 [Default:CheckIn:E] Running Loop=1 2023-12-18T20:12:46.819Z,1702930366.819 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.300065 min 2023-12-18T20:12:46.819Z,1702930366.819 [Default:CheckIn:E] Stopped 2023-12-18T20:12:46.819Z,1702930366.819 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T20:12:46.819Z,1702930366.819 [Default:CheckIn] Stopped 2023-12-18T20:12:46.819Z,1702930366.819 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T20:12:46.820Z,1702930366.820 [Default:CheckIn](INFO): Running loop #25 2023-12-18T20:12:46.820Z,1702930366.820 [Default:CheckIn] Running Loop=25 2023-12-18T20:12:46.820Z,1702930366.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T20:12:46.820Z,1702930366.820 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T20:12:48.835Z,1702930368.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201248.00,A,3648.17938,N,12147.27408,W,0.039,333.05,181223,,,A*73 2023-12-18T20:12:48.837Z,1702930368.837 [NAL9602](INFO): GPS fix at 20231218T201248: (36.802990, -121.787901) 2023-12-18T20:12:48.849Z,1702930368.849 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T20:12:48.849Z,1702930368.849 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T20:12:56.643Z,1702930376.643 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231218T184442/Courier0048.lzma 2023-12-18T20:12:57.646Z,1702930377.646 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0048.lzma.bak 2023-12-18T20:12:57.646Z,1702930377.646 [DataOverHttps](INFO): SBD MOMSN=19119456 2023-12-18T20:13:13.968Z,1702930393.968 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20231218T184442/Express0049.lzma 2023-12-18T20:13:14.970Z,1702930394.970 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0049.lzma.bak 2023-12-18T20:13:14.971Z,1702930394.971 [DataOverHttps](INFO): SBD MOMSN=19119459 2023-12-18T20:13:16.317Z,1702930396.317 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T20:13:16.317Z,1702930396.317 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T20:13:16.317Z,1702930396.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T20:13:27.619Z,1702930407.619 [NAL9602](INFO): SBD MO Status=2, MOMSN=35867, MT Status=2, MTMSN=0 2023-12-18T20:13:27.619Z,1702930407.619 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T20:13:44.587Z,1702930424.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=35867, MT Status=2, MTMSN=0 2023-12-18T20:13:44.587Z,1702930424.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T20:14:10.445Z,1702930450.445 [NAL9602](INFO): SBD MO Status=2, MOMSN=35867, MT Status=2, MTMSN=0 2023-12-18T20:14:10.445Z,1702930450.445 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-12-18T20:14:45.990Z,1702930485.990 [NAL9602](INFO): SBD MO Status=1, MOMSN=35867, MT Status=0, MTMSN=0 2023-12-18T20:14:45.990Z,1702930485.990 [NAL9602](INFO): No messages in MT queue 2023-12-18T20:15:16.697Z,1702930516.697 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T20:18:16.896Z,1702930696.896 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T20:18:16.896Z,1702930696.896 [Default:CheckIn:C.Wait] Stopped 2023-12-18T20:18:16.896Z,1702930696.896 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T20:18:16.897Z,1702930696.897 [Default:CheckIn:D] Running Loop=1 2023-12-18T20:18:17.302Z,1702930697.302 [Default:CheckIn:D] Stopped 2023-12-18T20:18:17.302Z,1702930697.302 [Default:CheckIn:E] Running Loop=1 2023-12-18T20:18:17.722Z,1702930697.722 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.814681 min 2023-12-18T20:18:17.722Z,1702930697.722 [Default:CheckIn:E] Stopped 2023-12-18T20:18:17.722Z,1702930697.722 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T20:18:17.722Z,1702930697.722 [Default:CheckIn] Stopped 2023-12-18T20:18:17.722Z,1702930697.722 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T20:18:17.723Z,1702930697.723 [Default:CheckIn](INFO): Running loop #26 2023-12-18T20:18:17.723Z,1702930697.723 [Default:CheckIn] Running Loop=26 2023-12-18T20:18:17.723Z,1702930697.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T20:18:17.723Z,1702930697.723 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T20:18:19.711Z,1702930699.711 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201819.00,A,3648.17469,N,12147.28208,W,0.680,213.92,181223,,,A*74 2023-12-18T20:18:19.713Z,1702930699.713 [NAL9602](INFO): GPS fix at 20231218T201819: (36.802912, -121.788035) 2023-12-18T20:18:19.787Z,1702930699.787 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T20:18:19.787Z,1702930699.787 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T20:18:27.993Z,1702930707.993 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231218T184442/Courier0051.lzma 2023-12-18T20:18:28.994Z,1702930708.994 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0051.lzma.bak 2023-12-18T20:18:28.994Z,1702930708.994 [DataOverHttps](INFO): SBD MOMSN=19119473 2023-12-18T20:18:45.460Z,1702930725.460 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20231218T184442/Express0052.lzma 2023-12-18T20:18:46.462Z,1702930726.462 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0052.lzma.bak 2023-12-18T20:18:46.462Z,1702930726.462 [DataOverHttps](INFO): SBD MOMSN=19119476 2023-12-18T20:18:48.029Z,1702930728.029 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T20:18:48.029Z,1702930728.029 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T20:18:48.029Z,1702930728.029 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T20:20:01.930Z,1702930801.930 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-12-18T20:20:01.930Z,1702930801.930 [DropWeight] Hardware Fault, FailCount= 1 2023-12-18T20:20:01.930Z,1702930801.930 [DropWeight](ERROR): Hardware Fault 2023-12-18T20:20:02.065Z,1702930802.065 [CommandExec](FAULT): Scheduling is paused 2023-12-18T20:20:02.065Z,1702930802.065 [CBIT](INFO): Critical error at 20231218T202001 2023-12-18T20:20:02.076Z,1702930802.076 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-12-18T20:20:02.077Z,1702930802.077 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-12-18T20:20:02.433Z,1702930802.433 [CBIT](INFO): Critical error at 20231218T202002 2023-12-18T20:23:22.302Z,1702931002.302 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-12-18T20:23:48.571Z,1702931028.571 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T20:23:48.571Z,1702931028.571 [Default:CheckIn:C.Wait] Stopped 2023-12-18T20:23:48.571Z,1702931028.571 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T20:23:48.571Z,1702931028.571 [Default:CheckIn:D] Running Loop=1 2023-12-18T20:23:48.977Z,1702931028.977 [Default:CheckIn:D] Stopped 2023-12-18T20:23:48.977Z,1702931028.977 [Default:CheckIn:E] Running Loop=1 2023-12-18T20:23:49.391Z,1702931029.391 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.342594 min 2023-12-18T20:23:49.392Z,1702931029.392 [Default:CheckIn:E] Stopped 2023-12-18T20:23:49.392Z,1702931029.392 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T20:23:49.392Z,1702931029.392 [Default:CheckIn] Stopped 2023-12-18T20:23:49.392Z,1702931029.392 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T20:23:49.392Z,1702931029.392 [Default:CheckIn](INFO): Running loop #27 2023-12-18T20:23:49.392Z,1702931029.392 [Default:CheckIn] Running Loop=27 2023-12-18T20:23:49.392Z,1702931029.392 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T20:23:49.392Z,1702931029.392 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T20:23:51.395Z,1702931031.395 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202350.00,A,3648.16739,N,12147.27957,W,0.447,209.98,181223,,,A*70 2023-12-18T20:23:51.397Z,1702931031.397 [NAL9602](INFO): GPS fix at 20231218T202350: (36.802790, -121.787993) 2023-12-18T20:23:51.409Z,1702931031.409 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T20:23:51.409Z,1702931031.409 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T20:23:58.656Z,1702931038.656 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20231218T184442/Courier0054.lzma 2023-12-18T20:24:00.104Z,1702931040.104 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0054.lzma.bak 2023-12-18T20:24:00.105Z,1702931040.105 [DataOverHttps](INFO): SBD MOMSN=19119479 2023-12-18T20:24:16.100Z,1702931056.100 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20231218T184442/Express0055.lzma 2023-12-18T20:24:17.102Z,1702931057.102 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0055.lzma.bak 2023-12-18T20:24:17.102Z,1702931057.102 [DataOverHttps](INFO): SBD MOMSN=19119484 2023-12-18T20:24:18.522Z,1702931058.522 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T20:24:18.522Z,1702931058.522 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T20:24:18.522Z,1702931058.522 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T20:24:23.341Z,1702931063.341 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T20:29:19.092Z,1702931359.092 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T20:29:19.092Z,1702931359.092 [Default:CheckIn:C.Wait] Stopped 2023-12-18T20:29:19.092Z,1702931359.092 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T20:29:19.092Z,1702931359.092 [Default:CheckIn:D] Running Loop=1 2023-12-18T20:29:19.496Z,1702931359.496 [Default:CheckIn:D] Stopped 2023-12-18T20:29:19.496Z,1702931359.496 [Default:CheckIn:E] Running Loop=1 2023-12-18T20:29:19.923Z,1702931359.923 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.851237 min 2023-12-18T20:29:19.923Z,1702931359.923 [Default:CheckIn:E] Stopped 2023-12-18T20:29:19.923Z,1702931359.923 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T20:29:19.923Z,1702931359.923 [Default:CheckIn] Stopped 2023-12-18T20:29:19.923Z,1702931359.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T20:29:19.923Z,1702931359.923 [Default:CheckIn](INFO): Running loop #28 2023-12-18T20:29:19.923Z,1702931359.923 [Default:CheckIn] Running Loop=28 2023-12-18T20:29:19.924Z,1702931359.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T20:29:19.924Z,1702931359.924 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T20:29:52.608Z,1702931392.608 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-12-18T20:29:52.688Z,1702931392.688 [NAL9602](ERROR): received: +CSQ:1 OK867, 0, 0, 0, 0 OK 2023-12-18T20:33:55.858Z,1702931635.858 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.00, +0 +0.00, 20.00,6793.01 2023-12-18T20:34:20.101Z,1702931660.101 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-18T20:29:19.9Z 2023-12-18T20:34:20.101Z,1702931660.101 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T20:34:20.101Z,1702931660.101 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-12-18T20:34:24.113Z,1702931664.113 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-12-18T20:34:27.899Z,1702931667.899 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231218T184442/Courier0057.lzma 2023-12-18T20:34:28.902Z,1702931668.902 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Courier0057.lzma.bak 2023-12-18T20:34:28.902Z,1702931668.902 [DataOverHttps](INFO): SBD MOMSN=19119496 2023-12-18T20:34:45.251Z,1702931685.251 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231218T184442/Express0058.lzma 2023-12-18T20:34:46.254Z,1702931686.254 [DataOverHttps](INFO): Moved sent file to Logs/20231218T184442/Express0058.lzma.bak 2023-12-18T20:34:46.254Z,1702931686.254 [DataOverHttps](INFO): SBD MOMSN=19119498 2023-12-18T20:34:47.652Z,1702931687.652 [Default:CheckIn:Read_Iridium] Stopped 2023-12-18T20:34:47.652Z,1702931687.652 [Default:CheckIn:C.Wait] Running Loop=1 2023-12-18T20:34:47.652Z,1702931687.652 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-12-18T20:34:54.518Z,1702931694.518 [NAL9602](INFO): Not Powering down - fast GPS 2023-12-18T20:35:02.173Z,1702931702.173 [CBIT](INFO): Clearing failed state for component DropWeight 2023-12-18T20:35:02.173Z,1702931702.173 [DropWeight] No Fault, FailCount= 1 2023-12-18T20:36:37.179Z,1702931797.179 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-12-18T20:36:48.073Z,1702931808.073 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-12-18T20:36:58.565Z,1702931818.565 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-12-18T20:37:09.073Z,1702931829.073 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-12-18T20:37:19.987Z,1702931839.987 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-12-18T20:37:30.894Z,1702931850.894 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-12-18T20:37:41.396Z,1702931861.396 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-12-18T20:37:51.901Z,1702931871.901 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-12-18T20:38:02.746Z,1702931882.746 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-12-18T20:38:02.748Z,1702931882.748 [BPC1](INFO): Received data from all battery sticks. 2023-12-18T20:39:48.201Z,1702931988.201 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-12-18T20:39:48.201Z,1702931988.201 [Default:CheckIn:C.Wait] Stopped 2023-12-18T20:39:48.201Z,1702931988.201 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-12-18T20:39:48.201Z,1702931988.201 [Default:CheckIn:D] Running Loop=1 2023-12-18T20:39:48.613Z,1702931988.613 [Default:CheckIn:D] Stopped 2023-12-18T20:39:48.613Z,1702931988.613 [Default:CheckIn:E] Running Loop=1 2023-12-18T20:39:49.003Z,1702931989.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.336523 min 2023-12-18T20:39:49.003Z,1702931989.003 [Default:CheckIn:E] Stopped 2023-12-18T20:39:49.003Z,1702931989.003 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-12-18T20:39:49.003Z,1702931989.003 [Default:CheckIn] Stopped 2023-12-18T20:39:49.003Z,1702931989.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T20:39:49.003Z,1702931989.003 [Default:CheckIn](INFO): Running loop #29 2023-12-18T20:39:49.004Z,1702931989.004 [Default:CheckIn] Running Loop=29 2023-12-18T20:39:49.004Z,1702931989.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-12-18T20:39:49.004Z,1702931989.004 [Default:CheckIn:Read_GPS] Running Loop=1 2023-12-18T20:39:50.613Z,1702931990.613 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-12-18T20:39:50.613Z,1702931990.613 [NAL9602] Data Fault, FailCount= 1 2023-12-18T20:39:50.613Z,1702931990.613 [NAL9602](ERROR): Data Fault 2023-12-18T20:39:50.666Z,1702931990.666 [CBIT](ERROR): Data Fault in component: NAL9602 2023-12-18T20:39:51.017Z,1702931991.017 [NAL9602](INFO): Powering down 2023-12-18T20:39:51.868Z,1702931991.868 [CBIT](INFO): Clearing failed state for component NAL9602 2023-12-18T20:39:51.868Z,1702931991.868 [NAL9602] No Fault, FailCount= 1 2023-12-18T20:40:21.322Z,1702932021.322 [NAL9602](INFO): Powering up NAL9602 2023-12-18T20:40:32.221Z,1702932032.221 [NAL9602](INFO): NAL9602 initialized 2023-12-18T20:43:05.945Z,1702932185.945 [CommandExec](IMPORTANT): got command burn 2023-12-18T20:43:05.945Z,1702932185.945 [CommandExec](FAULT): Incomplete syntax. Try: help burn 2023-12-18T20:43:36.634Z,1702932216.634 [CommandExec](IMPORTANT): got command quit 2023-12-18T20:43:37.642Z,1702932217.642 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:37.642Z,1702932217.642 [CommandExec](INFO): Uninitializing the command executive. 2023-12-18T20:43:37.642Z,1702932217.642 [CommandExec](INFO): Uninitializing the command scheduler. 2023-12-18T20:43:37.642Z,1702932217.642 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:37.740Z,1702932217.740 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-12-18T20:43:37.740Z,1702932217.740 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-12-18T20:43:37.740Z,1702932217.740 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:37.741Z,1702932217.741 [NavChartDb](INFO): Join timeout helper Thread ID is 7078 2023-12-18T20:43:37.944Z,1702932217.944 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:37.945Z,1702932217.945 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:37.960Z,1702932217.960 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2023-12-18T20:43:37.960Z,1702932217.960 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:37.961Z,1702932217.961 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7079 2023-12-18T20:43:38.289Z,1702932218.289 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:38.289Z,1702932218.289 [WetLabsUBAT](INFO): Powering down 2023-12-18T20:43:38.290Z,1702932218.290 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:38.304Z,1702932218.304 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-12-18T20:43:38.304Z,1702932218.304 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:38.305Z,1702932218.305 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7080 2023-12-18T20:43:38.760Z,1702932218.760 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:38.761Z,1702932218.761 [WetLabsBB2FL](INFO): Powering down 2023-12-18T20:43:38.761Z,1702932218.761 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:38.772Z,1702932218.772 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-12-18T20:43:38.772Z,1702932218.772 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:38.773Z,1702932218.773 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7081 2023-12-18T20:43:39.328Z,1702932219.328 [CTD_Seabird](INFO): Powering down 2023-12-18T20:43:39.340Z,1702932219.340 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:39.340Z,1702932219.340 [CTD_Seabird](INFO): Powering down 2023-12-18T20:43:39.352Z,1702932219.352 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:39.356Z,1702932219.356 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-12-18T20:43:39.357Z,1702932219.357 [DAT ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:39.357Z,1702932219.357 [DAT](INFO): Join timeout helper Thread ID is 7082 2023-12-18T20:43:39.404Z,1702932219.404 [DAT](INFO): Powering down 2023-12-18T20:43:39.476Z,1702932219.476 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:39.477Z,1702932219.477 [DAT](INFO): Powering down 2023-12-18T20:43:39.478Z,1702932219.478 [DAT ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:39.479Z,1702932219.479 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-12-18T20:43:39.479Z,1702932219.479 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:39.480Z,1702932219.480 [Radio_Surface](INFO): Join timeout helper Thread ID is 7083 2023-12-18T20:43:39.500Z,1702932219.500 [Radio_Surface](INFO): Powering down 2023-12-18T20:43:39.501Z,1702932219.501 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:39.501Z,1702932219.501 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:39.510Z,1702932219.510 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-12-18T20:43:39.510Z,1702932219.510 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:39.511Z,1702932219.511 [Onboard](INFO): Join timeout helper Thread ID is 7084 2023-12-18T20:43:39.760Z,1702932219.760 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-12-18T20:43:40.997Z,1702932220.997 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:40.999Z,1702932220.999 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.013Z,1702932221.013 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-12-18T20:43:41.013Z,1702932221.013 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.013Z,1702932221.013 [DataOverHttps](INFO): Join timeout helper Thread ID is 7085 2023-12-18T20:43:41.768Z,1702932221.768 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:41.769Z,1702932221.769 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.777Z,1702932221.777 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-12-18T20:43:41.777Z,1702932221.777 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.777Z,1702932221.777 [BackseatComponent](INFO): Join timeout helper Thread ID is 7086 2023-12-18T20:43:41.888Z,1702932221.888 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:41.888Z,1702932221.888 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.905Z,1702932221.905 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-12-18T20:43:41.905Z,1702932221.905 [logger ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.905Z,1702932221.905 [logger](INFO): Join timeout helper Thread ID is 7087 2023-12-18T20:43:41.940Z,1702932221.940 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:41.940Z,1702932221.940 [logger ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.945Z,1702932221.945 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-12-18T20:43:41.945Z,1702932221.945 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.945Z,1702932221.945 [CommandLine](INFO): Join timeout helper Thread ID is 7088 2023-12-18T20:43:41.956Z,1702932221.956 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:41.956Z,1702932221.956 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.965Z,1702932221.965 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-12-18T20:43:41.965Z,1702932221.965 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.965Z,1702932221.965 [CommandExec](INFO): Join timeout helper Thread ID is 7089 2023-12-18T20:43:41.966Z,1702932221.966 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-12-18T20:43:41.966Z,1702932221.966 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:41.967Z,1702932221.967 [controlThread](INFO): Join timeout helper Thread ID is 7090 2023-12-18T20:43:42.148Z,1702932222.148 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-12-18T20:43:42.148Z,1702932222.148 [controlThread](DEBUG): Uninitializing ControlThread 2023-12-18T20:43:42.149Z,1702932222.149 [AHRS_M2](INFO): Powering down 2023-12-18T20:43:42.221Z,1702932222.221 [NAL9602](INFO): Powering down 2023-12-18T20:43:42.292Z,1702932222.292 [RDI_Pathfinder](INFO): Powering down 2023-12-18T20:43:42.294Z,1702932222.294 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-12-18T20:43:42.294Z,1702932222.294 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-12-18T20:43:42.295Z,1702932222.295 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-12-18T20:43:42.295Z,1702932222.295 [MissionManager](INFO): Uninitializing Mission Default 2023-12-18T20:43:42.295Z,1702932222.295 [Default] Stopped 2023-12-18T20:43:42.295Z,1702932222.295 [Default](DEBUG): Aggregate::uninitialize Default 2023-12-18T20:43:42.296Z,1702932222.296 [Default:B.GoToSurface] Stopped 2023-12-18T20:43:42.296Z,1702932222.296 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-12-18T20:43:42.296Z,1702932222.296 [Default:CheckIn] Stopped 2023-12-18T20:43:42.296Z,1702932222.296 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-12-18T20:43:42.296Z,1702932222.296 [Default:CheckIn:Read_GPS] Stopped 2023-12-18T20:43:42.299Z,1702932222.299 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-12-18T20:43:42.299Z,1702932222.299 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-12-18T20:43:42.299Z,1702932222.299 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-12-18T20:43:42.300Z,1702932222.300 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-12-18T20:43:42.300Z,1702932222.300 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-12-18T20:43:42.300Z,1702932222.300 [BuoyancyServo](INFO): Powering down 2023-12-18T20:43:42.312Z,1702932222.312 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-12-18T20:43:42.312Z,1702932222.312 [ElevatorServo](INFO): Powering down 2023-12-18T20:43:42.313Z,1702932222.313 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-12-18T20:43:42.313Z,1702932222.313 [MassServo](INFO): Powering down 2023-12-18T20:43:42.314Z,1702932222.314 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-12-18T20:43:42.314Z,1702932222.314 [RudderServo](INFO): Powering down 2023-12-18T20:43:42.315Z,1702932222.315 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-12-18T20:43:42.315Z,1702932222.315 [ThrusterServo](INFO): Powering down 2023-12-18T20:43:42.316Z,1702932222.316 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-12-18T20:43:42.316Z,1702932222.316 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-12-18T20:43:42.316Z,1702932222.316 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-12-18T20:43:42.317Z,1702932222.317 [CBIT](DEBUG): Powering off loads. 2023-12-18T20:43:42.328Z,1702932222.328 [CBIT](DEBUG): Disabling WDT. 2023-12-18T20:43:42.340Z,1702932222.340 [CBIT](DEBUG): Opening all GF detection circuits. 2023-12-18T20:43:42.340Z,1702932222.340 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.392Z,1702932222.392 [DAT ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.399Z,1702932222.399 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.402Z,1702932222.402 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.408Z,1702932222.408 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.452Z,1702932222.452 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.523Z,1702932222.523 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.525Z,1702932222.525 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.529Z,1702932222.529 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.537Z,1702932222.537 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-12-18T20:43:42.625Z,1702932222.625 [logger ThreadHandler](INFO): Thread cancelled.