2021-04-26T16:35:54.369Z,1619454954.369 [CommandLine](IMPORTANT): got command restart logs
2021-04-26T16:36:23.689Z,1619454983.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-04-26T16:36:27.823Z,1619454987.823 [NAL9602](INFO): SBD MO Status=1, MOMSN=27548, MT Status=0, MTMSN=0
2021-04-26T16:36:27.873Z,1619454987.873 [NAL9602](INFO): Sent 39 bytes from file Logs/20210426T122822/Courier0061.lzma
2021-04-26T16:36:27.873Z,1619454987.873 [NAL9602](INFO): Packets left to send: 0
2021-04-26T16:36:39.183Z,1619454999.183 [NAL9602](INFO): SBD MO Status=1, MOMSN=27549, MT Status=0, MTMSN=0
2021-04-26T16:36:39.237Z,1619454999.237 [NAL9602](INFO): Sent 143 bytes from file Logs/20210426T122822/Express0059.lzma
2021-04-26T16:36:39.237Z,1619454999.237 [NAL9602](INFO): Packets left to send: 0
2021-04-26T16:36:53.479Z,1619455013.479 [NAL9602](INFO): SBD MO Status=2, MOMSN=27550, MT Status=2, MTMSN=0
2021-04-26T16:36:53.479Z,1619455013.479 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:36:54.837Z,1619455014.837 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-04-26T16:37:25.031Z,1619455045.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=27550, MT Status=2, MTMSN=0
2021-04-26T16:37:25.031Z,1619455045.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:37:25.961Z,1619455045.961 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-04-26T16:37:57.085Z,1619455077.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-04-26T16:38:02.519Z,1619455082.519 [NAL9602](INFO): SBD MO Status=1, MOMSN=27550, MT Status=0, MTMSN=0
2021-04-26T16:38:02.573Z,1619455082.573 [NAL9602](INFO): Sent 117 bytes from file Logs/20210426T122822/Express0062.lzma
2021-04-26T16:38:02.573Z,1619455082.573 [NAL9602](INFO): Packets left to send: 0
2021-04-26T16:38:14.642Z,1619455094.642 [NAL9602](INFO): SBD MO Status=0, MOMSN=27551, MT Status=0, MTMSN=0
2021-04-26T16:38:28.209Z,1619455108.209 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-04-26T16:38:56.311Z,1619455136.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=27552, MT Status=2, MTMSN=0
2021-04-26T16:38:56.311Z,1619455136.311 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:38:59.333Z,1619455139.333 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-04-26T16:39:28.273Z,1619455168.273 [NAL9602](INFO): SBD MO Status=1, MOMSN=27552, MT Status=0, MTMSN=0
2021-04-26T16:39:28.325Z,1619455168.325 [NAL9602](INFO): Sent 151 bytes from file Logs/20210426T163554/Courier0000.lzma
2021-04-26T16:39:28.325Z,1619455168.325 [NAL9602](INFO): Packets left to send: 0
2021-04-26T16:39:30.497Z,1619455170.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-04-26T16:39:44.660Z,1619455184.660 [NAL9602](INFO): SBD MO Status=2, MOMSN=27553, MT Status=2, MTMSN=0
2021-04-26T16:39:44.660Z,1619455184.660 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:40:01.621Z,1619455201.621 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-04-26T16:40:10.057Z,1619455210.057 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002914
2021-04-26T16:40:17.589Z,1619455217.589 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20210426T163554/Express0001.lzma
2021-04-26T16:40:18.590Z,1619455218.590 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0001.lzma.bak
2021-04-26T16:40:18.590Z,1619455218.590 [DataOverHttps](INFO): SBD MOMSN=15604727
2021-04-26T16:40:19.901Z,1619455219.901 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T16:40:19.902Z,1619455219.902 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T16:40:19.902Z,1619455219.902 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T16:40:43.719Z,1619455243.719 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T16:45:20.588Z,1619455520.588 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T16:45:20.588Z,1619455520.588 [Default:CheckIn:C.Wait] Stopped
2021-04-26T16:45:20.588Z,1619455520.588 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T16:45:20.589Z,1619455520.589 [Default:CheckIn:D] Running Loop=1
2021-04-26T16:45:21.007Z,1619455521.007 [Default:CheckIn:D] Stopped
2021-04-26T16:45:21.007Z,1619455521.007 [Default:CheckIn:E] Running Loop=1
2021-04-26T16:45:21.421Z,1619455521.421 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.295964 min 
2021-04-26T16:45:21.421Z,1619455521.421 [Default:CheckIn:E] Stopped
2021-04-26T16:45:21.421Z,1619455521.421 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T16:45:21.421Z,1619455521.421 [Default:CheckIn] Stopped
2021-04-26T16:45:21.421Z,1619455521.421 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T16:45:21.421Z,1619455521.421 [Default:CheckIn](INFO): Running loop #4
2021-04-26T16:45:21.421Z,1619455521.421 [Default:CheckIn] Running Loop=4
2021-04-26T16:45:21.422Z,1619455521.422 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T16:45:21.422Z,1619455521.422 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T16:45:23.403Z,1619455523.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164420.00,A,3647.87851,N,12150.71219,W,5.482,76.85,260421,,,A*43
2021-04-26T16:45:23.406Z,1619455523.406 [NAL9602](INFO): GPS fix at 20210426T164420: (36.797975, -121.845203)
2021-04-26T16:45:23.453Z,1619455523.453 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T16:45:23.453Z,1619455523.453 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T16:45:34.393Z,1619455534.393 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20210426T163554/Courier0003.lzma
2021-04-26T16:45:35.395Z,1619455535.395 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0003.lzma.bak
2021-04-26T16:45:35.395Z,1619455535.395 [DataOverHttps](INFO): SBD MOMSN=15604735
2021-04-26T16:45:53.025Z,1619455553.025 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20210426T163554/Express0004.lzma
2021-04-26T16:45:54.026Z,1619455554.026 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0004.lzma.bak
2021-04-26T16:45:54.026Z,1619455554.026 [DataOverHttps](INFO): SBD MOMSN=15604741
2021-04-26T16:45:55.401Z,1619455555.401 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T16:45:55.402Z,1619455555.402 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T16:45:55.402Z,1619455555.402 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T16:46:15.175Z,1619455575.175 [NAL9602](INFO): SBD MO Status=2, MOMSN=27553, MT Status=2, MTMSN=0
2021-04-26T16:46:15.175Z,1619455575.175 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:49:22.283Z,1619455762.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=27553, MT Status=2, MTMSN=0
2021-04-26T16:49:22.283Z,1619455762.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:50:25.782Z,1619455825.782 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-26T16:50:56.090Z,1619455856.090 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T16:50:56.090Z,1619455856.090 [Default:CheckIn:C.Wait] Stopped
2021-04-26T16:50:56.090Z,1619455856.090 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T16:50:56.091Z,1619455856.091 [Default:CheckIn:D] Running Loop=1
2021-04-26T16:50:56.492Z,1619455856.492 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T16:50:56.504Z,1619455856.504 [Default:CheckIn:D] Stopped
2021-04-26T16:50:56.505Z,1619455856.505 [Default:CheckIn:E] Running Loop=1
2021-04-26T16:50:56.919Z,1619455856.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.887579 min 
2021-04-26T16:50:56.919Z,1619455856.919 [Default:CheckIn:E] Stopped
2021-04-26T16:50:56.919Z,1619455856.919 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T16:50:56.919Z,1619455856.919 [Default:CheckIn] Stopped
2021-04-26T16:50:56.919Z,1619455856.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T16:50:56.920Z,1619455856.920 [Default:CheckIn](INFO): Running loop #5
2021-04-26T16:50:56.920Z,1619455856.920 [Default:CheckIn] Running Loop=5
2021-04-26T16:50:56.920Z,1619455856.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T16:50:56.920Z,1619455856.920 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T16:50:58.916Z,1619455858.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164956.00,A,3648.23099,N,12148.18614,W,29.449,76.31,260421,,,D*7E
2021-04-26T16:50:58.918Z,1619455858.918 [NAL9602](INFO): GPS fix at 20210426T164956: (36.803850, -121.803102)
2021-04-26T16:50:58.957Z,1619455858.957 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T16:50:58.958Z,1619455858.958 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T16:51:06.660Z,1619455866.660 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163554/Courier0006.lzma
2021-04-26T16:51:07.662Z,1619455867.662 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0006.lzma.bak
2021-04-26T16:51:07.662Z,1619455867.662 [DataOverHttps](INFO): SBD MOMSN=15604751
2021-04-26T16:51:26.781Z,1619455886.781 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20210426T163554/Express0007.lzma
2021-04-26T16:51:27.783Z,1619455887.783 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0007.lzma.bak
2021-04-26T16:51:27.783Z,1619455887.783 [DataOverHttps](INFO): SBD MOMSN=15604759
2021-04-26T16:51:29.671Z,1619455889.671 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-26T16:51:29.753Z,1619455889.753 [NAL9602](ERROR): received: 
+CSQ:0

OK553, 2, 0, 0, 0

OK
2021-04-26T16:51:29.765Z,1619455889.765 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T16:51:29.765Z,1619455889.765 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T16:51:29.765Z,1619455889.765 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T16:54:00.039Z,1619456040.039 [NAL9602](INFO): SBD MO Status=2, MOMSN=27553, MT Status=2, MTMSN=0
2021-04-26T16:54:00.039Z,1619456040.039 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:54:17.019Z,1619456057.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=27553, MT Status=2, MTMSN=0
2021-04-26T16:54:17.019Z,1619456057.019 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:54:59.431Z,1619456099.431 [NAL9602](INFO): SBD MO Status=1, MOMSN=27553, MT Status=0, MTMSN=0
2021-04-26T16:54:59.431Z,1619456099.431 [NAL9602](INFO): No messages in MT queue
2021-04-26T16:55:30.131Z,1619456130.131 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T16:56:30.344Z,1619456190.344 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T16:56:30.344Z,1619456190.344 [Default:CheckIn:C.Wait] Stopped
2021-04-26T16:56:30.344Z,1619456190.344 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T16:56:30.344Z,1619456190.344 [Default:CheckIn:D] Running Loop=1
2021-04-26T16:56:30.745Z,1619456190.745 [Default:CheckIn:D] Stopped
2021-04-26T16:56:30.745Z,1619456190.745 [Default:CheckIn:E] Running Loop=1
2021-04-26T16:56:31.161Z,1619456191.161 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.458248 min 
2021-04-26T16:56:31.161Z,1619456191.161 [Default:CheckIn:E] Stopped
2021-04-26T16:56:31.161Z,1619456191.161 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T16:56:31.161Z,1619456191.161 [Default:CheckIn] Stopped
2021-04-26T16:56:31.162Z,1619456191.162 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T16:56:31.162Z,1619456191.162 [Default:CheckIn](INFO): Running loop #6
2021-04-26T16:56:31.162Z,1619456191.162 [Default:CheckIn] Running Loop=6
2021-04-26T16:56:31.162Z,1619456191.162 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T16:56:31.162Z,1619456191.162 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T16:56:33.163Z,1619456193.163 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165530.00,A,3648.18063,N,12147.17105,W,6.143,201.77,260421,,,D*7B
2021-04-26T16:56:33.166Z,1619456193.166 [NAL9602](INFO): GPS fix at 20210426T165530: (36.803010, -121.786184)
2021-04-26T16:56:33.202Z,1619456193.202 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T16:56:33.202Z,1619456193.202 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T16:56:40.740Z,1619456200.740 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0009.lzma
2021-04-26T16:56:41.742Z,1619456201.742 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0009.lzma.bak
2021-04-26T16:56:41.742Z,1619456201.742 [DataOverHttps](INFO): SBD MOMSN=15604775
2021-04-26T16:56:57.493Z,1619456217.493 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210426T163554/Express0010.lzma
2021-04-26T16:56:58.494Z,1619456218.494 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0010.lzma.bak
2021-04-26T16:56:58.494Z,1619456218.494 [DataOverHttps](INFO): SBD MOMSN=15604778
2021-04-26T16:56:59.857Z,1619456219.857 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T16:56:59.857Z,1619456219.857 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T16:56:59.857Z,1619456219.857 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T16:57:15.179Z,1619456235.179 [NAL9602](INFO): SBD MO Status=2, MOMSN=27554, MT Status=2, MTMSN=0
2021-04-26T16:57:15.179Z,1619456235.179 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T16:57:32.987Z,1619456252.987 [NAL9602](INFO): SBD MO Status=1, MOMSN=27554, MT Status=0, MTMSN=0
2021-04-26T16:57:32.988Z,1619456252.988 [NAL9602](INFO): No messages in MT queue
2021-04-26T16:58:03.722Z,1619456283.722 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:02:00.490Z,1619456520.490 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:02:00.490Z,1619456520.490 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:02:00.490Z,1619456520.490 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:02:00.491Z,1619456520.491 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:02:00.896Z,1619456520.896 [Default:CheckIn:D] Stopped
2021-04-26T17:02:00.896Z,1619456520.896 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:02:01.305Z,1619456521.305 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.960779 min 
2021-04-26T17:02:01.305Z,1619456521.305 [Default:CheckIn:E] Stopped
2021-04-26T17:02:01.305Z,1619456521.305 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:02:01.305Z,1619456521.305 [Default:CheckIn] Stopped
2021-04-26T17:02:01.306Z,1619456521.306 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:02:01.306Z,1619456521.306 [Default:CheckIn](INFO): Running loop #7
2021-04-26T17:02:01.306Z,1619456521.306 [Default:CheckIn] Running Loop=7
2021-04-26T17:02:01.306Z,1619456521.306 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:02:01.306Z,1619456521.306 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:02:03.319Z,1619456523.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170100.00,A,3648.13651,N,12147.20558,W,0.136,302.16,260421,,,D*7D
2021-04-26T17:02:03.322Z,1619456523.322 [NAL9602](INFO): GPS fix at 20210426T170100: (36.802275, -121.786760)
2021-04-26T17:02:03.333Z,1619456523.333 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:02:03.333Z,1619456523.333 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:02:11.464Z,1619456531.464 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163554/Courier0012.lzma
2021-04-26T17:02:12.466Z,1619456532.466 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0012.lzma.bak
2021-04-26T17:02:12.466Z,1619456532.466 [DataOverHttps](INFO): SBD MOMSN=15604789
2021-04-26T17:02:28.761Z,1619456548.761 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210426T163554/Express0013.lzma
2021-04-26T17:02:29.762Z,1619456549.762 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0013.lzma.bak
2021-04-26T17:02:29.763Z,1619456549.763 [DataOverHttps](INFO): SBD MOMSN=15604795
2021-04-26T17:02:31.201Z,1619456551.201 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:02:31.201Z,1619456551.201 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:02:31.201Z,1619456551.201 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:03:55.263Z,1619456635.263 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-04-26T17:04:24.000Z,1619456664.000 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-26T17:04:35.661Z,1619456675.661 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-04-26T17:04:35.663Z,1619456675.663 [BPC1](INFO): Received data from all battery sticks.
2021-04-26T17:04:45.363Z,1619456685.363 [NAL9602](INFO): SBD MO Status=1, MOMSN=27555, MT Status=0, MTMSN=0
2021-04-26T17:04:45.363Z,1619456685.363 [NAL9602](INFO): No messages in MT queue
2021-04-26T17:05:16.058Z,1619456716.058 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:07:31.842Z,1619456851.842 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:07:31.842Z,1619456851.842 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:07:31.842Z,1619456851.842 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:07:31.842Z,1619456851.842 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:07:32.254Z,1619456852.254 [Default:CheckIn:D] Stopped
2021-04-26T17:07:32.255Z,1619456852.255 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:07:32.658Z,1619456852.658 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.483415 min 
2021-04-26T17:07:32.658Z,1619456852.658 [Default:CheckIn:E] Stopped
2021-04-26T17:07:32.659Z,1619456852.659 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:07:32.659Z,1619456852.659 [Default:CheckIn] Stopped
2021-04-26T17:07:32.659Z,1619456852.659 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:07:32.659Z,1619456852.659 [Default:CheckIn](INFO): Running loop #8
2021-04-26T17:07:32.659Z,1619456852.659 [Default:CheckIn] Running Loop=8
2021-04-26T17:07:32.659Z,1619456852.659 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:07:32.659Z,1619456852.659 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:07:34.655Z,1619456854.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170631.00,A,3648.13684,N,12147.21060,W,0.039,231.06,260421,,,D*71
2021-04-26T17:07:34.657Z,1619456854.657 [NAL9602](INFO): GPS fix at 20210426T170631: (36.802281, -121.786843)
2021-04-26T17:07:34.735Z,1619456854.735 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:07:34.735Z,1619456854.735 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:07:41.873Z,1619456861.873 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20210426T163554/Courier0015.lzma
2021-04-26T17:07:42.874Z,1619456862.874 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0015.lzma.bak
2021-04-26T17:07:42.874Z,1619456862.874 [DataOverHttps](INFO): SBD MOMSN=15604806
2021-04-26T17:07:52.494Z,1619456872.494 [NAL9602](INFO): SBD MO Status=1, MOMSN=27556, MT Status=0, MTMSN=0
2021-04-26T17:07:52.494Z,1619456872.494 [NAL9602](INFO): No messages in MT queue
2021-04-26T17:07:58.780Z,1619456878.780 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210426T163554/Express0016.lzma
2021-04-26T17:07:59.782Z,1619456879.782 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0016.lzma.bak
2021-04-26T17:07:59.783Z,1619456879.783 [DataOverHttps](INFO): SBD MOMSN=15604812
2021-04-26T17:08:01.344Z,1619456881.344 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:08:01.344Z,1619456881.344 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:08:01.344Z,1619456881.344 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:08:23.138Z,1619456903.138 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:13:01.929Z,1619457181.929 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:13:01.929Z,1619457181.929 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:13:01.929Z,1619457181.929 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:13:01.929Z,1619457181.929 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:13:02.320Z,1619457182.320 [Default:CheckIn:D] Stopped
2021-04-26T17:13:02.336Z,1619457182.336 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.984513 min 
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn:E] Stopped
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn] Stopped
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn](INFO): Running loop #9
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn] Running Loop=9
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:13:02.730Z,1619457182.730 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:13:04.742Z,1619457184.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171202.00,A,3648.16678,N,12147.28167,W,0.641,237.05,260421,,,D*71
2021-04-26T17:13:04.744Z,1619457184.744 [NAL9602](INFO): GPS fix at 20210426T171202: (36.802780, -121.788028)
2021-04-26T17:13:04.755Z,1619457184.755 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:13:04.755Z,1619457184.755 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:13:12.420Z,1619457192.420 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0018.lzma
2021-04-26T17:13:13.422Z,1619457193.422 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0018.lzma.bak
2021-04-26T17:13:13.423Z,1619457193.423 [DataOverHttps](INFO): SBD MOMSN=15604822
2021-04-26T17:13:33.549Z,1619457213.549 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210426T163554/Express0019.lzma
2021-04-26T17:13:34.551Z,1619457214.551 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0019.lzma.bak
2021-04-26T17:13:34.551Z,1619457214.551 [DataOverHttps](INFO): SBD MOMSN=15604828
2021-04-26T17:13:36.372Z,1619457216.372 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:13:36.373Z,1619457216.373 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:13:36.373Z,1619457216.373 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:14:02.627Z,1619457242.627 [NAL9602](INFO): SBD MO Status=2, MOMSN=27557, MT Status=2, MTMSN=0
2021-04-26T17:14:02.627Z,1619457242.627 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T17:15:19.383Z,1619457319.383 [NAL9602](INFO): SBD MO Status=2, MOMSN=27557, MT Status=2, MTMSN=0
2021-04-26T17:15:19.383Z,1619457319.383 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T17:18:07.451Z,1619457487.451 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-26T17:18:36.950Z,1619457516.950 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:18:36.950Z,1619457516.950 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:18:36.950Z,1619457516.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:18:36.950Z,1619457516.950 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:18:37.363Z,1619457517.363 [Default:CheckIn:D] Stopped
2021-04-26T17:18:37.363Z,1619457517.363 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:18:37.760Z,1619457517.760 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.568555 min 
2021-04-26T17:18:37.760Z,1619457517.760 [Default:CheckIn:E] Stopped
2021-04-26T17:18:37.760Z,1619457517.760 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:18:37.781Z,1619457517.781 [Default:CheckIn] Stopped
2021-04-26T17:18:37.781Z,1619457517.781 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:18:37.781Z,1619457517.781 [Default:CheckIn](INFO): Running loop #10
2021-04-26T17:18:37.781Z,1619457517.781 [Default:CheckIn] Running Loop=10
2021-04-26T17:18:37.781Z,1619457517.781 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:18:37.781Z,1619457517.781 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:18:38.151Z,1619457518.151 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:18:39.775Z,1619457519.775 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171737.00,A,3648.16384,N,12147.28078,W,0.894,299.20,260421,,,A*7B
2021-04-26T17:18:39.778Z,1619457519.778 [NAL9602](INFO): GPS fix at 20210426T171737: (36.802731, -121.788013)
2021-04-26T17:18:39.789Z,1619457519.789 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:18:39.789Z,1619457519.789 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:18:47.244Z,1619457527.244 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0021.lzma
2021-04-26T17:18:48.246Z,1619457528.246 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0021.lzma.bak
2021-04-26T17:18:48.246Z,1619457528.246 [DataOverHttps](INFO): SBD MOMSN=15604839
2021-04-26T17:19:04.029Z,1619457544.029 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20210426T163554/Express0022.lzma
2021-04-26T17:19:05.030Z,1619457545.030 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0022.lzma.bak
2021-04-26T17:19:05.031Z,1619457545.031 [DataOverHttps](INFO): SBD MOMSN=15604842
2021-04-26T17:19:06.457Z,1619457546.457 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:19:06.457Z,1619457546.457 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:19:06.457Z,1619457546.457 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:19:10.474Z,1619457550.474 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-26T17:19:10.557Z,1619457550.556 [NAL9602](ERROR): received: 
+CSQ:0

OK557, 2, 0, 0, 0

OK
2021-04-26T17:22:18.737Z,1619457738.737 [NAL9602](INFO): SBD MO Status=1, MOMSN=27557, MT Status=0, MTMSN=0
2021-04-26T17:22:18.737Z,1619457738.737 [NAL9602](INFO): No messages in MT queue
2021-04-26T17:22:49.458Z,1619457769.458 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:24:07.041Z,1619457847.041 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:24:07.041Z,1619457847.041 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:24:07.041Z,1619457847.041 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:24:07.042Z,1619457847.042 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:24:07.441Z,1619457847.441 [Default:CheckIn:D] Stopped
2021-04-26T17:24:07.441Z,1619457847.441 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:24:07.834Z,1619457847.834 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.069865 min 
2021-04-26T17:24:07.834Z,1619457847.834 [Default:CheckIn:E] Stopped
2021-04-26T17:24:07.834Z,1619457847.834 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:24:07.835Z,1619457847.835 [Default:CheckIn] Stopped
2021-04-26T17:24:07.835Z,1619457847.835 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:24:07.835Z,1619457847.835 [Default:CheckIn](INFO): Running loop #11
2021-04-26T17:24:07.835Z,1619457847.835 [Default:CheckIn] Running Loop=11
2021-04-26T17:24:07.835Z,1619457847.835 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:24:07.835Z,1619457847.835 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:24:09.848Z,1619457849.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172307.00,A,3648.16559,N,12147.28476,W,0.389,299.20,260421,,,A*74
2021-04-26T17:24:09.850Z,1619457849.850 [NAL9602](INFO): GPS fix at 20210426T172307: (36.802760, -121.788079)
2021-04-26T17:24:09.860Z,1619457849.860 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:24:09.860Z,1619457849.860 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:24:16.856Z,1619457856.856 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0024.lzma
2021-04-26T17:24:17.859Z,1619457857.859 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0024.lzma.bak
2021-04-26T17:24:17.859Z,1619457857.859 [DataOverHttps](INFO): SBD MOMSN=15604852
2021-04-26T17:24:32.471Z,1619457872.471 [NAL9602](INFO): SBD MO Status=2, MOMSN=27558, MT Status=2, MTMSN=0
2021-04-26T17:24:32.471Z,1619457872.471 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T17:24:34.196Z,1619457874.196 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210426T163554/Express0025.lzma
2021-04-26T17:24:35.198Z,1619457875.198 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0025.lzma.bak
2021-04-26T17:24:35.199Z,1619457875.199 [DataOverHttps](INFO): SBD MOMSN=15604855
2021-04-26T17:24:36.521Z,1619457876.521 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:24:36.521Z,1619457876.521 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:24:36.521Z,1619457876.521 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:25:22.579Z,1619457922.579 [NAL9602](INFO): SBD MO Status=2, MOMSN=27558, MT Status=2, MTMSN=0
2021-04-26T17:25:22.579Z,1619457922.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T17:29:12.466Z,1619458152.466 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-26T17:29:37.151Z,1619458177.151 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:29:37.151Z,1619458177.151 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:29:37.151Z,1619458177.151 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:29:37.152Z,1619458177.152 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:29:37.550Z,1619458177.550 [Default:CheckIn:D] Stopped
2021-04-26T17:29:37.550Z,1619458177.550 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:29:37.967Z,1619458177.967 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.571672 min 
2021-04-26T17:29:37.967Z,1619458177.967 [Default:CheckIn:E] Stopped
2021-04-26T17:29:37.967Z,1619458177.967 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:29:37.967Z,1619458177.967 [Default:CheckIn] Stopped
2021-04-26T17:29:37.967Z,1619458177.967 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:29:37.967Z,1619458177.967 [Default:CheckIn](INFO): Running loop #12
2021-04-26T17:29:37.967Z,1619458177.967 [Default:CheckIn] Running Loop=12
2021-04-26T17:29:37.968Z,1619458177.968 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:29:37.968Z,1619458177.968 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:29:39.970Z,1619458179.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172837.00,A,3648.16979,N,12147.27955,W,0.369,0.00,260421,,,A*7F
2021-04-26T17:29:39.972Z,1619458179.972 [NAL9602](INFO): GPS fix at 20210426T172837: (36.802830, -121.787993)
2021-04-26T17:29:39.999Z,1619458179.999 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:29:39.999Z,1619458179.999 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:29:46.896Z,1619458186.896 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0027.lzma
2021-04-26T17:29:47.898Z,1619458187.898 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0027.lzma.bak
2021-04-26T17:29:47.898Z,1619458187.898 [DataOverHttps](INFO): SBD MOMSN=15604869
2021-04-26T17:30:03.717Z,1619458203.717 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20210426T163554/Express0028.lzma
2021-04-26T17:30:04.718Z,1619458204.718 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0028.lzma.bak
2021-04-26T17:30:04.719Z,1619458204.719 [DataOverHttps](INFO): SBD MOMSN=15604872
2021-04-26T17:30:06.252Z,1619458206.252 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:30:06.252Z,1619458206.252 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:30:06.252Z,1619458206.252 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:30:12.688Z,1619458212.688 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:35:06.815Z,1619458506.815 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:35:06.816Z,1619458506.816 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:35:06.816Z,1619458506.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:35:06.816Z,1619458506.816 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:35:07.220Z,1619458507.220 [Default:CheckIn:D] Stopped
2021-04-26T17:35:07.220Z,1619458507.220 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.066170 min 
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn:E] Stopped
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn] Stopped
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn](INFO): Running loop #13
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn] Running Loop=13
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:35:07.638Z,1619458507.638 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:35:09.632Z,1619458509.632 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173406.00,A,3648.16937,N,12147.27930,W,0.058,158.91,260421,,,A*7C
2021-04-26T17:35:09.634Z,1619458509.634 [NAL9602](INFO): GPS fix at 20210426T173406: (36.802823, -121.787988)
2021-04-26T17:35:09.645Z,1619458509.645 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:35:09.646Z,1619458509.646 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:35:17.212Z,1619458517.212 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0030.lzma
2021-04-26T17:35:18.214Z,1619458518.214 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0030.lzma.bak
2021-04-26T17:35:18.215Z,1619458518.215 [DataOverHttps](INFO): SBD MOMSN=15604884
2021-04-26T17:35:40.054Z,1619458540.054 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-26T17:35:40.129Z,1619458540.129 [NAL9602](ERROR): received: 
+CSQ:0

OK558, 2, 0, 0, 0

OK
2021-04-26T17:35:40.397Z,1619458540.397 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210426T163554/Express0031.lzma
2021-04-26T17:35:41.399Z,1619458541.399 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0031.lzma.bak
2021-04-26T17:35:41.399Z,1619458541.399 [DataOverHttps](INFO): SBD MOMSN=15604888
2021-04-26T17:35:42.910Z,1619458542.910 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:35:42.910Z,1619458542.910 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:35:42.910Z,1619458542.910 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:40:12.393Z,1619458812.393 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-26T17:40:43.083Z,1619458843.083 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:40:43.501Z,1619458843.501 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:40:43.502Z,1619458843.502 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:40:43.502Z,1619458843.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:40:43.502Z,1619458843.502 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:40:43.914Z,1619458843.914 [Default:CheckIn:D] Stopped
2021-04-26T17:40:43.914Z,1619458843.914 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:40:44.306Z,1619458844.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.677734 min 
2021-04-26T17:40:44.306Z,1619458844.306 [Default:CheckIn:E] Stopped
2021-04-26T17:40:44.306Z,1619458844.306 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:40:44.306Z,1619458844.306 [Default:CheckIn] Stopped
2021-04-26T17:40:44.306Z,1619458844.306 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:40:44.307Z,1619458844.307 [Default:CheckIn](INFO): Running loop #14
2021-04-26T17:40:44.307Z,1619458844.307 [Default:CheckIn] Running Loop=14
2021-04-26T17:40:44.307Z,1619458844.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:40:44.307Z,1619458844.307 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:40:46.323Z,1619458846.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173943.00,A,3648.16903,N,12147.28173,W,0.078,18.94,260421,,,A*45
2021-04-26T17:40:46.326Z,1619458846.326 [NAL9602](INFO): GPS fix at 20210426T173943: (36.802817, -121.788029)
2021-04-26T17:40:46.336Z,1619458846.336 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:40:46.364Z,1619458846.364 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:40:53.533Z,1619458853.533 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0033.lzma
2021-04-26T17:40:54.534Z,1619458854.534 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0033.lzma.bak
2021-04-26T17:40:54.535Z,1619458854.535 [DataOverHttps](INFO): SBD MOMSN=15604897
2021-04-26T17:41:10.333Z,1619458870.333 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20210426T163554/Express0034.lzma
2021-04-26T17:41:11.338Z,1619458871.338 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0034.lzma.bak
2021-04-26T17:41:11.338Z,1619458871.338 [DataOverHttps](INFO): SBD MOMSN=15604900
2021-04-26T17:41:13.006Z,1619458873.006 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:41:13.006Z,1619458873.006 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:41:13.006Z,1619458873.006 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:41:17.022Z,1619458877.022 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-26T17:41:17.105Z,1619458877.105 [NAL9602](ERROR): received: 
+CSQ:1

OK558, 2, 0, 0, 0

OK
2021-04-26T17:45:48.646Z,1619459148.646 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-26T17:46:13.716Z,1619459173.716 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:46:13.716Z,1619459173.716 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:46:13.716Z,1619459173.716 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:46:13.716Z,1619459173.716 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:46:14.127Z,1619459174.127 [Default:CheckIn:D] Stopped
2021-04-26T17:46:14.127Z,1619459174.127 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.181291 min 
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn:E] Stopped
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn] Stopped
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn](INFO): Running loop #15
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn] Running Loop=15
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:46:14.521Z,1619459174.521 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:46:16.526Z,1619459176.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174513.00,A,3648.16903,N,12147.28227,W,0.583,15.63,260421,,,A*4D
2021-04-26T17:46:16.528Z,1619459176.528 [NAL9602](INFO): GPS fix at 20210426T174513: (36.802817, -121.788038)
2021-04-26T17:46:16.539Z,1619459176.539 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:46:16.539Z,1619459176.539 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:46:23.337Z,1619459183.337 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0036.lzma
2021-04-26T17:46:24.334Z,1619459184.334 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0036.lzma.bak
2021-04-26T17:46:24.335Z,1619459184.335 [DataOverHttps](INFO): SBD MOMSN=15604913
2021-04-26T17:46:40.053Z,1619459200.053 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20210426T163554/Express0037.lzma
2021-04-26T17:46:41.055Z,1619459201.055 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0037.lzma.bak
2021-04-26T17:46:41.055Z,1619459201.055 [DataOverHttps](INFO): SBD MOMSN=15604916
2021-04-26T17:46:42.404Z,1619459202.404 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:46:42.404Z,1619459202.404 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:46:42.404Z,1619459202.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:46:48.443Z,1619459208.443 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:51:43.026Z,1619459503.026 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:51:43.026Z,1619459503.026 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:51:43.026Z,1619459503.026 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:51:43.027Z,1619459503.027 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:51:43.442Z,1619459503.442 [Default:CheckIn:D] Stopped
2021-04-26T17:51:43.442Z,1619459503.442 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:51:43.822Z,1619459503.822 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.669881 min 
2021-04-26T17:51:43.822Z,1619459503.822 [Default:CheckIn:E] Stopped
2021-04-26T17:51:43.822Z,1619459503.822 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:51:43.822Z,1619459503.822 [Default:CheckIn] Stopped
2021-04-26T17:51:43.823Z,1619459503.823 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:51:43.823Z,1619459503.823 [Default:CheckIn](INFO): Running loop #16
2021-04-26T17:51:43.823Z,1619459503.823 [Default:CheckIn] Running Loop=16
2021-04-26T17:51:43.823Z,1619459503.823 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:51:43.823Z,1619459503.823 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:51:45.846Z,1619459505.846 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175043.00,A,3648.16567,N,12147.28297,W,0.156,34.61,260421,,,A*44
2021-04-26T17:51:45.849Z,1619459505.849 [NAL9602](INFO): GPS fix at 20210426T175043: (36.802761, -121.788049)
2021-04-26T17:51:45.859Z,1619459505.859 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:51:45.859Z,1619459505.859 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:51:52.800Z,1619459512.800 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210426T163554/Courier0039.lzma
2021-04-26T17:51:53.802Z,1619459513.802 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0039.lzma.bak
2021-04-26T17:51:53.803Z,1619459513.803 [DataOverHttps](INFO): SBD MOMSN=15604927
2021-04-26T17:52:09.592Z,1619459529.592 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210426T163554/Express0040.lzma
2021-04-26T17:52:10.594Z,1619459530.594 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0040.lzma.bak
2021-04-26T17:52:10.595Z,1619459530.595 [DataOverHttps](INFO): SBD MOMSN=15604930
2021-04-26T17:52:12.121Z,1619459532.121 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:52:12.121Z,1619459532.121 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:52:12.121Z,1619459532.121 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:52:16.542Z,1619459536.542 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-26T17:52:16.625Z,1619459536.625 [NAL9602](ERROR): received: 
+CSQ:0

OK558, 2, 0, 0, 0

OK
2021-04-26T17:55:50.687Z,1619459750.687 [NAL9602](INFO): SBD MO Status=2, MOMSN=27558, MT Status=2, MTMSN=0
2021-04-26T17:55:50.687Z,1619459750.687 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T17:56:58.160Z,1619459818.160 [NAL9602](INFO): SBD MO Status=2, MOMSN=27558, MT Status=2, MTMSN=0
2021-04-26T17:56:58.160Z,1619459818.160 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-26T17:56:58.562Z,1619459818.562 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-26T17:57:12.716Z,1619459832.716 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T17:57:12.716Z,1619459832.716 [Default:CheckIn:C.Wait] Stopped
2021-04-26T17:57:12.716Z,1619459832.716 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T17:57:12.717Z,1619459832.717 [Default:CheckIn:D] Running Loop=1
2021-04-26T17:57:13.127Z,1619459833.127 [Default:CheckIn:D] Stopped
2021-04-26T17:57:13.127Z,1619459833.127 [Default:CheckIn:E] Running Loop=1
2021-04-26T17:57:13.514Z,1619459833.514 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.164632 min 
2021-04-26T17:57:13.514Z,1619459833.514 [Default:CheckIn:E] Stopped
2021-04-26T17:57:13.514Z,1619459833.514 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T17:57:13.514Z,1619459833.514 [Default:CheckIn] Stopped
2021-04-26T17:57:13.514Z,1619459833.514 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T17:57:13.515Z,1619459833.515 [Default:CheckIn](INFO): Running loop #17
2021-04-26T17:57:13.515Z,1619459833.515 [Default:CheckIn] Running Loop=17
2021-04-26T17:57:13.515Z,1619459833.515 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T17:57:13.515Z,1619459833.515 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T17:57:15.539Z,1619459835.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175612.00,A,3648.16435,N,12147.27911,W,0.505,34.61,260421,,,A*48
2021-04-26T17:57:15.542Z,1619459835.542 [NAL9602](INFO): GPS fix at 20210426T175612: (36.802739, -121.787985)
2021-04-26T17:57:15.552Z,1619459835.552 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T17:57:15.552Z,1619459835.552 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T17:57:26.572Z,1619459846.572 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210426T163554/Courier0042.lzma
2021-04-26T17:57:27.575Z,1619459847.575 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0042.lzma.bak
2021-04-26T17:57:27.575Z,1619459847.575 [DataOverHttps](INFO): SBD MOMSN=15604933
2021-04-26T17:57:47.036Z,1619459867.036 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20210426T163554/Express0043.lzma
2021-04-26T17:57:48.038Z,1619459868.038 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0043.lzma.bak
2021-04-26T17:57:48.039Z,1619459868.039 [DataOverHttps](INFO): SBD MOMSN=15604936
2021-04-26T17:57:48.372Z,1619459868.372 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-26T17:57:49.597Z,1619459869.597 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T17:57:49.597Z,1619459869.597 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T17:57:49.598Z,1619459869.598 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T17:58:49.777Z,1619459929.777 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2021-04-26T17:58:49.777Z,1619459929.777 [DropWeight] Hardware Fault, FailCount= 1
2021-04-26T17:58:49.777Z,1619459929.777 [DropWeight](ERROR): Hardware Fault
2021-04-26T17:58:49.795Z,1619459929.795 [CommandLine](FAULT): Scheduling is paused
2021-04-26T17:58:49.796Z,1619459929.796 [CBIT](INFO): Critical error at 20210426T175849
2021-04-26T17:58:49.826Z,1619459929.826 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-04-26T17:58:49.827Z,1619459929.827 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-04-26T17:58:50.203Z,1619459930.203 [CBIT](INFO): Critical error at 20210426T175849
2021-04-26T18:02:50.330Z,1619460170.330 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-26T18:02:50.330Z,1619460170.330 [Default:CheckIn:C.Wait] Stopped
2021-04-26T18:02:50.330Z,1619460170.330 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T18:02:50.330Z,1619460170.330 [Default:CheckIn:D] Running Loop=1
2021-04-26T18:02:50.709Z,1619460170.709 [Default:CheckIn:D] Stopped
2021-04-26T18:02:50.709Z,1619460170.709 [Default:CheckIn:E] Running Loop=1
2021-04-26T18:02:51.133Z,1619460171.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.790999 min 
2021-04-26T18:02:51.133Z,1619460171.133 [Default:CheckIn:E] Stopped
2021-04-26T18:02:51.133Z,1619460171.133 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-26T18:02:51.133Z,1619460171.133 [Default:CheckIn] Stopped
2021-04-26T18:02:51.133Z,1619460171.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T18:02:51.133Z,1619460171.133 [Default:CheckIn](INFO): Running loop #18
2021-04-26T18:02:51.133Z,1619460171.133 [Default:CheckIn] Running Loop=18
2021-04-26T18:02:51.134Z,1619460171.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-26T18:02:51.134Z,1619460171.134 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-26T18:02:53.137Z,1619460173.137 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180150.00,A,3648.13683,N,12147.28955,W,4.413,29.23,260421,,,A*4E
2021-04-26T18:02:53.140Z,1619460173.140 [NAL9602](INFO): GPS fix at 20210426T180150: (36.802281, -121.788159)
2021-04-26T18:02:53.151Z,1619460173.151 [Default:CheckIn:Read_GPS] Stopped
2021-04-26T18:02:53.151Z,1619460173.151 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-26T18:03:00.045Z,1619460180.045 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20210426T163554/Courier0045.lzma
2021-04-26T18:03:01.046Z,1619460181.046 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Courier0045.lzma.bak
2021-04-26T18:03:01.047Z,1619460181.047 [DataOverHttps](INFO): SBD MOMSN=15604940
2021-04-26T18:03:17.117Z,1619460197.117 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20210426T163554/Express0046.lzma
2021-04-26T18:03:18.118Z,1619460198.118 [DataOverHttps](INFO): Moved sent file to Logs/20210426T163554/Express0046.lzma.bak
2021-04-26T18:03:18.119Z,1619460198.119 [DataOverHttps](INFO): SBD MOMSN=15604945
2021-04-26T18:03:19.396Z,1619460199.396 [Default:CheckIn:Read_Iridium] Stopped
2021-04-26T18:03:19.396Z,1619460199.396 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-26T18:03:19.396Z,1619460199.396 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-26T18:05:42.007Z,1619460342.007 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-26T18:05:42.032Z,1619460342.032 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-26T18:05:49.665Z,1619460349.665 [CommandLine](IMPORTANT): got command quit
2021-04-26T18:05:50.669Z,1619460350.669 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:50.670Z,1619460350.670 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:50.724Z,1619460350.724 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-04-26T18:05:50.725Z,1619460350.725 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:50.725Z,1619460350.725 [CommandLine](INFO): Join timeout helper Thread ID is 2797
2021-04-26T18:05:50.726Z,1619460350.726 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-04-26T18:05:50.726Z,1619460350.726 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:50.726Z,1619460350.726 [NavChartDb](INFO): Join timeout helper Thread ID is 2798
2021-04-26T18:05:51.085Z,1619460351.085 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:51.085Z,1619460351.085 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:51.104Z,1619460351.104 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-04-26T18:05:51.105Z,1619460351.105 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:51.105Z,1619460351.105 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2799
2021-04-26T18:05:51.381Z,1619460351.381 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:51.381Z,1619460351.381 [WetLabsBB2FL](INFO): Powering down
2021-04-26T18:05:51.382Z,1619460351.382 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:51.388Z,1619460351.388 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-04-26T18:05:51.389Z,1619460351.389 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:51.389Z,1619460351.389 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2800
2021-04-26T18:05:52.121Z,1619460352.121 [CTD_Seabird](INFO): Powering down
2021-04-26T18:05:52.133Z,1619460352.133 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:52.141Z,1619460352.141 [CTD_Seabird](INFO): Powering down
2021-04-26T18:05:52.153Z,1619460352.153 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:52.161Z,1619460352.161 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-04-26T18:05:52.161Z,1619460352.161 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:52.161Z,1619460352.161 [Radio_Surface](INFO): Join timeout helper Thread ID is 2801
2021-04-26T18:05:52.241Z,1619460352.241 [Radio_Surface](INFO): Powering down
2021-04-26T18:05:52.242Z,1619460352.242 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:52.242Z,1619460352.242 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:52.249Z,1619460352.249 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-04-26T18:05:52.250Z,1619460352.250 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:52.250Z,1619460352.250 [Onboard](INFO): Join timeout helper Thread ID is 2802
2021-04-26T18:05:52.971Z,1619460352.971 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-26T18:05:53.038Z,1619460353.038 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-26T18:05:55.405Z,1619460355.405 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:55.405Z,1619460355.405 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:55.413Z,1619460355.413 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-04-26T18:05:55.413Z,1619460355.413 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:55.414Z,1619460355.414 [DataOverHttps](INFO): Join timeout helper Thread ID is 2803
2021-04-26T18:05:55.673Z,1619460355.673 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:55.674Z,1619460355.674 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:55.693Z,1619460355.693 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-04-26T18:05:55.693Z,1619460355.693 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:55.694Z,1619460355.694 [logger](INFO): Join timeout helper Thread ID is 2804
2021-04-26T18:05:55.733Z,1619460355.733 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:55.733Z,1619460355.733 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:55.737Z,1619460355.737 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-04-26T18:05:55.738Z,1619460355.738 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:55.738Z,1619460355.738 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-04-26T18:05:55.738Z,1619460355.738 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:55.738Z,1619460355.738 [controlThread](INFO): Join timeout helper Thread ID is 2805
2021-04-26T18:05:55.763Z,1619460355.763 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-26T18:05:55.763Z,1619460355.763 [controlThread](DEBUG): Uninitializing ControlThread
2021-04-26T18:05:55.764Z,1619460355.764 [AHRS_M2](INFO): Powering down
2021-04-26T18:05:55.838Z,1619460355.838 [NAL9602](INFO): Powering down
2021-04-26T18:05:55.839Z,1619460355.839 [AMEcho](INFO): Powering down
2021-04-26T18:05:55.840Z,1619460355.840 [DAT](INFO): Powering down
2021-04-26T18:05:55.957Z,1619460355.957 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-04-26T18:05:55.958Z,1619460355.958 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-04-26T18:05:55.959Z,1619460355.959 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-04-26T18:05:55.959Z,1619460355.959 [MissionManager](INFO): Uninitializing Mission Default
2021-04-26T18:05:55.959Z,1619460355.959 [Default] Stopped
2021-04-26T18:05:55.959Z,1619460355.959 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-26T18:05:55.960Z,1619460355.960 [Default:B.GoToSurface] Stopped
2021-04-26T18:05:55.960Z,1619460355.960 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-26T18:05:55.960Z,1619460355.960 [Default:CheckIn] Stopped
2021-04-26T18:05:55.960Z,1619460355.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-26T18:05:55.960Z,1619460355.960 [Default:CheckIn:C.Wait] Stopped
2021-04-26T18:05:55.960Z,1619460355.960 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-26T18:05:55.963Z,1619460355.963 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-04-26T18:05:55.963Z,1619460355.963 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-04-26T18:05:55.963Z,1619460355.963 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-04-26T18:05:55.964Z,1619460355.964 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-04-26T18:05:55.964Z,1619460355.964 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-26T18:05:55.964Z,1619460355.964 [BuoyancyServo](INFO): Powering down
2021-04-26T18:05:55.977Z,1619460355.977 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-04-26T18:05:55.977Z,1619460355.977 [ElevatorServo](INFO): Powering down
2021-04-26T18:05:55.978Z,1619460355.978 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-04-26T18:05:55.978Z,1619460355.978 [MassServo](INFO): Powering down
2021-04-26T18:05:55.978Z,1619460355.978 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-26T18:05:55.979Z,1619460355.979 [RudderServo](INFO): Powering down
2021-04-26T18:05:55.979Z,1619460355.979 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-04-26T18:05:55.979Z,1619460355.979 [ThrusterServo](INFO): Powering down
2021-04-26T18:05:55.980Z,1619460355.980 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-04-26T18:05:55.981Z,1619460355.981 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-04-26T18:05:55.981Z,1619460355.981 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-26T18:05:55.981Z,1619460355.981 [CBIT](DEBUG): Powering off loads.
2021-04-26T18:05:55.992Z,1619460355.992 [CBIT](DEBUG): Disabling WDT.
2021-04-26T18:05:56.004Z,1619460356.004 [CBIT](DEBUG): Opening all GF detection circuits.
2021-04-26T18:05:56.006Z,1619460356.006 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:56.064Z,1619460356.064 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:56.067Z,1619460356.067 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:56.073Z,1619460356.073 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:56.143Z,1619460356.143 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:56.147Z,1619460356.147 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:56.187Z,1619460356.187 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-26T18:05:56.262Z,1619460356.262 [logger ThreadHandler](INFO): Thread cancelled.