2023-05-26T20:43:03.809Z,1685133783.809 [CommandExec](IMPORTANT): got command restart logs 2023-05-26T20:43:04.308Z,1685133784.308 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:43:20.674Z,1685133800.674 [NAL9602](INFO): SBD MO Status=1, MOMSN=26222, MT Status=0, MTMSN=0 2023-05-26T20:43:20.724Z,1685133800.724 [NAL9602](INFO): Sent 39 bytes from file Logs/20230526T141500/Courier0039.lzma 2023-05-26T20:43:20.724Z,1685133800.724 [NAL9602](INFO): Packets left to send: 0 2023-05-26T20:43:30.854Z,1685133810.854 [NAL9602](INFO): SBD MO Status=1, MOMSN=26223, MT Status=0, MTMSN=0 2023-05-26T20:43:30.912Z,1685133810.912 [NAL9602](INFO): Sent 131 bytes from file Logs/20230526T141500/Express0037.lzma 2023-05-26T20:43:30.912Z,1685133810.912 [NAL9602](INFO): Packets left to send: 0 2023-05-26T20:43:39.453Z,1685133819.453 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:43:45.494Z,1685133825.494 [NAL9602](INFO): SBD MO Status=1, MOMSN=26224, MT Status=0, MTMSN=0 2023-05-26T20:43:45.548Z,1685133825.548 [NAL9602](INFO): Sent 116 bytes from file Logs/20230526T141500/Express0040.lzma 2023-05-26T20:43:45.548Z,1685133825.548 [NAL9602](INFO): Packets left to send: 0 2023-05-26T20:43:55.587Z,1685133835.587 [NAL9602](INFO): SBD MO Status=0, MOMSN=26225, MT Status=0, MTMSN=0 2023-05-26T20:44:14.250Z,1685133854.250 [NAL9602](INFO): SBD MO Status=1, MOMSN=26226, MT Status=0, MTMSN=0 2023-05-26T20:44:14.304Z,1685133854.304 [NAL9602](INFO): Sent 151 bytes from file Logs/20230526T204303/Courier0000.lzma 2023-05-26T20:44:14.304Z,1685133854.304 [NAL9602](INFO): Packets left to send: 0 2023-05-26T20:44:14.592Z,1685133854.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:44:26.449Z,1685133866.449 [NAL9602](INFO): SBD MO Status=1, MOMSN=26227, MT Status=0, MTMSN=0 2023-05-26T20:44:26.504Z,1685133866.504 [NAL9602](INFO): Sent 313 bytes from file Logs/20230526T204303/Express0001.lzma 2023-05-26T20:44:26.504Z,1685133866.504 [NAL9602](INFO): Packets left to send: 0 2023-05-26T20:44:43.450Z,1685133883.450 [NAL9602](INFO): SBD MO Status=0, MOMSN=26228, MT Status=0, MTMSN=0 2023-05-26T20:44:43.548Z,1685133883.548 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T20:44:43.548Z,1685133883.548 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T20:44:43.548Z,1685133883.548 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T20:44:49.740Z,1685133889.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:45:14.126Z,1685133914.126 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T20:45:24.880Z,1685133924.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:46:00.020Z,1685133960.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:46:35.168Z,1685133995.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:47:10.308Z,1685134030.308 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:47:45.449Z,1685134065.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:48:20.588Z,1685134100.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:48:55.728Z,1685134135.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:49:30.868Z,1685134170.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:49:44.007Z,1685134184.007 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T20:49:44.007Z,1685134184.007 [Default:CheckIn:C.Wait] Stopped 2023-05-26T20:49:44.007Z,1685134184.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T20:49:44.007Z,1685134184.007 [Default:CheckIn:D] Running Loop=1 2023-05-26T20:49:44.418Z,1685134184.418 [Default:CheckIn:D] Stopped 2023-05-26T20:49:44.418Z,1685134184.418 [Default:CheckIn:E] Running Loop=1 2023-05-26T20:49:44.815Z,1685134184.815 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.584342 min 2023-05-26T20:49:44.816Z,1685134184.816 [Default:CheckIn:E] Stopped 2023-05-26T20:49:44.816Z,1685134184.816 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T20:49:44.816Z,1685134184.816 [Default:CheckIn] Stopped 2023-05-26T20:49:44.816Z,1685134184.816 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T20:49:44.816Z,1685134184.816 [Default:CheckIn](INFO): Running loop #8 2023-05-26T20:49:44.816Z,1685134184.816 [Default:CheckIn] Running Loop=8 2023-05-26T20:49:44.816Z,1685134184.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T20:49:44.816Z,1685134184.816 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T20:49:46.827Z,1685134186.827 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204946.00,A,3648.50779,N,12147.15525,W,6.317,114.28,260523,,,D*7B 2023-05-26T20:49:46.829Z,1685134186.829 [NAL9602](INFO): GPS fix at 20230526T204946: (36.808463, -121.785921) 2023-05-26T20:49:46.840Z,1685134186.840 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T20:49:46.840Z,1685134186.840 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T20:50:06.008Z,1685134206.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:50:14.384Z,1685134214.384 [NAL9602](INFO): SBD MO Status=1, MOMSN=26229, MT Status=0, MTMSN=0 2023-05-26T20:50:14.433Z,1685134214.433 [NAL9602](INFO): Sent 223 bytes from file Logs/20230526T204303/Courier0003.lzma 2023-05-26T20:50:14.434Z,1685134214.434 [NAL9602](INFO): Packets left to send: 0 2023-05-26T20:50:29.472Z,1685134229.472 [NAL9602](INFO): SBD MO Status=2, MOMSN=26230, MT Status=2, MTMSN=0 2023-05-26T20:50:29.473Z,1685134229.473 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-05-26T20:50:41.148Z,1685134241.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:51:02.202Z,1685134262.202 [NAL9602](INFO): SBD MO Status=1, MOMSN=26230, MT Status=0, MTMSN=0 2023-05-26T20:51:02.260Z,1685134262.260 [NAL9602](INFO): Sent 177 bytes from file Logs/20230526T204303/Express0004.lzma 2023-05-26T20:51:02.260Z,1685134262.260 [NAL9602](INFO): Packets left to send: 0 2023-05-26T20:51:16.288Z,1685134276.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-05-26T20:51:17.150Z,1685134277.150 [NAL9602](INFO): SBD MO Status=2, MOMSN=26231, MT Status=2, MTMSN=0 2023-05-26T20:51:17.150Z,1685134277.150 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-05-26T20:51:30.082Z,1685134290.082 [NAL9602](INFO): SBD MO Status=2, MOMSN=26231, MT Status=2, MTMSN=0 2023-05-26T20:51:30.082Z,1685134290.082 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-05-26T20:51:32.200Z,1685134292.200 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002996 2023-05-26T20:51:33.341Z,1685134293.341 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T20:51:33.341Z,1685134293.341 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T20:51:33.341Z,1685134293.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T20:51:35.023Z,1685134295.023 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2023-05-26T20:52:05.624Z,1685134325.624 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T20:56:33.891Z,1685134593.891 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T20:56:33.892Z,1685134593.892 [Default:CheckIn:C.Wait] Stopped 2023-05-26T20:56:33.892Z,1685134593.892 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T20:56:33.892Z,1685134593.892 [Default:CheckIn:D] Running Loop=1 2023-05-26T20:56:34.294Z,1685134594.294 [Default:CheckIn:D] Stopped 2023-05-26T20:56:34.294Z,1685134594.294 [Default:CheckIn:E] Running Loop=1 2023-05-26T20:56:34.706Z,1685134594.706 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.415617 min 2023-05-26T20:56:34.707Z,1685134594.707 [Default:CheckIn:E] Stopped 2023-05-26T20:56:34.708Z,1685134594.708 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T20:56:34.708Z,1685134594.708 [Default:CheckIn] Stopped 2023-05-26T20:56:34.709Z,1685134594.709 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T20:56:34.709Z,1685134594.709 [Default:CheckIn](INFO): Running loop #9 2023-05-26T20:56:34.709Z,1685134594.709 [Default:CheckIn] Running Loop=9 2023-05-26T20:56:34.709Z,1685134594.709 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T20:56:34.710Z,1685134594.710 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T20:56:36.714Z,1685134596.714 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205635.00,A,3648.30863,N,12147.14166,W,0.097,300.59,260523,,,D*7D 2023-05-26T20:56:36.717Z,1685134596.717 [NAL9602](INFO): GPS fix at 20230526T205635: (36.805144, -121.785694) 2023-05-26T20:56:36.744Z,1685134596.744 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T20:56:36.744Z,1685134596.744 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T20:56:44.447Z,1685134604.447 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230526T204303/Courier0006.lzma 2023-05-26T20:56:45.455Z,1685134605.455 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0006.lzma.bak 2023-05-26T20:56:45.457Z,1685134605.457 [DataOverHttps](INFO): SBD MOMSN=18362817 2023-05-26T20:56:50.446Z,1685134610.446 [NAL9602](INFO): SBD MO Status=0, MOMSN=26231, MT Status=0, MTMSN=0 2023-05-26T20:56:50.446Z,1685134610.446 [NAL9602](INFO): No messages in MT queue 2023-05-26T20:57:01.691Z,1685134621.691 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230526T204303/Express0007.lzma 2023-05-26T20:57:02.693Z,1685134622.693 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0007.lzma.bak 2023-05-26T20:57:02.693Z,1685134622.693 [DataOverHttps](INFO): SBD MOMSN=18362820 2023-05-26T20:57:04.198Z,1685134624.198 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T20:57:04.198Z,1685134624.198 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T20:57:04.198Z,1685134624.198 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T20:57:21.157Z,1685134641.157 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:02:04.771Z,1685134924.771 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:02:04.772Z,1685134924.772 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:02:04.772Z,1685134924.772 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:02:04.772Z,1685134924.772 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:02:05.173Z,1685134925.173 [Default:CheckIn:D] Stopped 2023-05-26T21:02:05.173Z,1685134925.173 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:02:05.596Z,1685134925.596 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.930261 min 2023-05-26T21:02:05.596Z,1685134925.596 [Default:CheckIn:E] Stopped 2023-05-26T21:02:05.596Z,1685134925.596 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:02:05.596Z,1685134925.596 [Default:CheckIn] Stopped 2023-05-26T21:02:05.596Z,1685134925.596 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:02:05.597Z,1685134925.597 [Default:CheckIn](INFO): Running loop #10 2023-05-26T21:02:05.597Z,1685134925.597 [Default:CheckIn] Running Loop=10 2023-05-26T21:02:05.597Z,1685134925.597 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:02:05.597Z,1685134925.597 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:02:07.586Z,1685134927.586 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210206.00,A,3648.30878,N,12147.14160,W,0.000,300.59,260523,,,D*7F 2023-05-26T21:02:07.589Z,1685134927.589 [NAL9602](INFO): GPS fix at 20230526T210206: (36.805146, -121.785693) 2023-05-26T21:02:07.636Z,1685134927.636 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:02:07.637Z,1685134927.637 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:02:14.739Z,1685134934.739 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230526T204303/Courier0009.lzma 2023-05-26T21:02:15.741Z,1685134935.741 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0009.lzma.bak 2023-05-26T21:02:15.741Z,1685134935.741 [DataOverHttps](INFO): SBD MOMSN=18362914 2023-05-26T21:02:32.295Z,1685134952.295 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230526T204303/Express0010.lzma 2023-05-26T21:02:33.297Z,1685134953.297 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0010.lzma.bak 2023-05-26T21:02:33.297Z,1685134953.297 [DataOverHttps](INFO): SBD MOMSN=18362917 2023-05-26T21:02:34.692Z,1685134954.692 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:02:34.693Z,1685134954.693 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:02:34.693Z,1685134954.693 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:02:35.883Z,1685134955.883 [NAL9602](INFO): SBD MO Status=2, MOMSN=26232, MT Status=2, MTMSN=0 2023-05-26T21:02:35.883Z,1685134955.883 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-05-26T21:02:44.387Z,1685134964.387 [NAL9602](INFO): SBD MO Status=0, MOMSN=26232, MT Status=0, MTMSN=0 2023-05-26T21:02:44.387Z,1685134964.387 [NAL9602](INFO): No messages in MT queue 2023-05-26T21:03:15.072Z,1685134995.072 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:05:41.726Z,1685135141.726 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-05-26T21:05:41.728Z,1685135141.728 [BPC1](INFO): Received data from all battery sticks. 2023-05-26T21:07:35.259Z,1685135255.259 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:07:35.259Z,1685135255.259 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:07:35.259Z,1685135255.259 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:07:35.260Z,1685135255.260 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:07:35.704Z,1685135255.704 [Default:CheckIn:D] Stopped 2023-05-26T21:07:35.704Z,1685135255.704 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:07:36.067Z,1685135256.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.439103 min 2023-05-26T21:07:36.067Z,1685135256.067 [Default:CheckIn:E] Stopped 2023-05-26T21:07:36.068Z,1685135256.068 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:07:36.068Z,1685135256.068 [Default:CheckIn] Stopped 2023-05-26T21:07:36.068Z,1685135256.068 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:07:36.068Z,1685135256.068 [Default:CheckIn](INFO): Running loop #11 2023-05-26T21:07:36.068Z,1685135256.068 [Default:CheckIn] Running Loop=11 2023-05-26T21:07:36.068Z,1685135256.068 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:07:36.068Z,1685135256.068 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:07:38.078Z,1685135258.078 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210737.00,A,3648.30910,N,12147.14152,W,0.039,300.59,260523,,,D*7C 2023-05-26T21:07:38.089Z,1685135258.089 [NAL9602](INFO): GPS fix at 20230526T210737: (36.805152, -121.785692) 2023-05-26T21:07:38.100Z,1685135258.100 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:07:38.100Z,1685135258.100 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:07:45.592Z,1685135265.592 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230526T204303/Courier0012.lzma 2023-05-26T21:07:46.593Z,1685135266.593 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0012.lzma.bak 2023-05-26T21:07:46.593Z,1685135266.593 [DataOverHttps](INFO): SBD MOMSN=18363011 2023-05-26T21:07:53.457Z,1685135273.457 [NAL9602](INFO): SBD MO Status=0, MOMSN=26233, MT Status=0, MTMSN=0 2023-05-26T21:07:53.457Z,1685135273.457 [NAL9602](INFO): No messages in MT queue 2023-05-26T21:08:02.827Z,1685135282.827 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230526T204303/Express0013.lzma 2023-05-26T21:08:03.829Z,1685135283.829 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0013.lzma.bak 2023-05-26T21:08:03.829Z,1685135283.829 [DataOverHttps](INFO): SBD MOMSN=18363014 2023-05-26T21:08:05.163Z,1685135285.163 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:08:05.163Z,1685135285.163 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:08:05.163Z,1685135285.163 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:08:24.128Z,1685135304.128 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:13:05.758Z,1685135585.758 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:13:05.759Z,1685135585.759 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:13:05.759Z,1685135585.759 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:13:05.759Z,1685135585.759 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:13:06.135Z,1685135586.135 [Default:CheckIn:D] Stopped 2023-05-26T21:13:06.135Z,1685135586.135 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:13:06.539Z,1685135586.539 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.946293 min 2023-05-26T21:13:06.539Z,1685135586.539 [Default:CheckIn:E] Stopped 2023-05-26T21:13:06.540Z,1685135586.540 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:13:06.540Z,1685135586.540 [Default:CheckIn] Stopped 2023-05-26T21:13:06.540Z,1685135586.540 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:13:06.540Z,1685135586.540 [Default:CheckIn](INFO): Running loop #12 2023-05-26T21:13:06.540Z,1685135586.540 [Default:CheckIn] Running Loop=12 2023-05-26T21:13:06.540Z,1685135586.540 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:13:06.540Z,1685135586.540 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:13:08.556Z,1685135588.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211307.00,A,3648.30790,N,12147.14178,W,1.069,192.94,260523,,,D*78 2023-05-26T21:13:08.558Z,1685135588.558 [NAL9602](INFO): GPS fix at 20230526T211307: (36.805132, -121.785696) 2023-05-26T21:13:08.569Z,1685135588.569 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:13:08.569Z,1685135588.569 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:13:15.995Z,1685135595.995 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230526T204303/Courier0015.lzma 2023-05-26T21:13:16.997Z,1685135596.997 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0015.lzma.bak 2023-05-26T21:13:16.998Z,1685135596.998 [DataOverHttps](INFO): SBD MOMSN=18363110 2023-05-26T21:13:21.878Z,1685135601.878 [NAL9602](INFO): SBD MO Status=0, MOMSN=26234, MT Status=0, MTMSN=0 2023-05-26T21:13:21.878Z,1685135601.878 [NAL9602](INFO): No messages in MT queue 2023-05-26T21:13:33.235Z,1685135613.235 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230526T204303/Express0016.lzma 2023-05-26T21:13:34.238Z,1685135614.238 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0016.lzma.bak 2023-05-26T21:13:34.238Z,1685135614.238 [DataOverHttps](INFO): SBD MOMSN=18363113 2023-05-26T21:13:35.643Z,1685135615.643 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:13:35.643Z,1685135615.643 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:13:35.643Z,1685135615.643 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:13:52.580Z,1685135632.580 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:18:36.210Z,1685135916.210 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:18:36.210Z,1685135916.210 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:18:36.210Z,1685135916.210 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:18:36.210Z,1685135916.210 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:18:36.602Z,1685135916.602 [Default:CheckIn:D] Stopped 2023-05-26T21:18:36.602Z,1685135916.602 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:18:37.007Z,1685135917.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.454085 min 2023-05-26T21:18:37.007Z,1685135917.007 [Default:CheckIn:E] Stopped 2023-05-26T21:18:37.007Z,1685135917.007 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:18:37.007Z,1685135917.007 [Default:CheckIn] Stopped 2023-05-26T21:18:37.007Z,1685135917.007 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:18:37.007Z,1685135917.007 [Default:CheckIn](INFO): Running loop #13 2023-05-26T21:18:37.008Z,1685135917.008 [Default:CheckIn] Running Loop=13 2023-05-26T21:18:37.008Z,1685135917.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:18:37.008Z,1685135917.008 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:18:39.019Z,1685135919.019 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211838.00,A,3648.13796,N,12147.20546,W,0.000,265.72,260523,,,D*7B 2023-05-26T21:18:39.021Z,1685135919.021 [NAL9602](INFO): GPS fix at 20230526T211838: (36.802299, -121.786758) 2023-05-26T21:18:39.033Z,1685135919.033 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:18:39.033Z,1685135919.033 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:18:46.455Z,1685135926.455 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230526T204303/Courier0018.lzma 2023-05-26T21:18:47.458Z,1685135927.458 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0018.lzma.bak 2023-05-26T21:18:47.458Z,1685135927.458 [DataOverHttps](INFO): SBD MOMSN=18363207 2023-05-26T21:19:03.791Z,1685135943.791 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230526T204303/Express0019.lzma 2023-05-26T21:19:04.793Z,1685135944.793 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0019.lzma.bak 2023-05-26T21:19:04.794Z,1685135944.794 [DataOverHttps](INFO): SBD MOMSN=18363210 2023-05-26T21:19:04.878Z,1685135944.878 [NAL9602](INFO): SBD MO Status=2, MOMSN=26235, MT Status=2, MTMSN=0 2023-05-26T21:19:04.878Z,1685135944.878 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-05-26T21:19:06.104Z,1685135946.104 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:19:06.104Z,1685135946.104 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:19:06.104Z,1685135946.104 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:20:42.641Z,1685136042.641 [NAL9602](INFO): SBD MO Status=0, MOMSN=26235, MT Status=0, MTMSN=0 2023-05-26T21:20:42.641Z,1685136042.641 [NAL9602](INFO): No messages in MT queue 2023-05-26T21:21:13.345Z,1685136073.345 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:24:06.672Z,1685136246.672 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:24:06.672Z,1685136246.672 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:24:06.672Z,1685136246.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:24:06.673Z,1685136246.673 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:24:07.075Z,1685136247.075 [Default:CheckIn:D] Stopped 2023-05-26T21:24:07.075Z,1685136247.075 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:24:07.510Z,1685136247.510 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.961971 min 2023-05-26T21:24:07.510Z,1685136247.510 [Default:CheckIn:E] Stopped 2023-05-26T21:24:07.510Z,1685136247.510 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:24:07.510Z,1685136247.510 [Default:CheckIn] Stopped 2023-05-26T21:24:07.510Z,1685136247.510 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:24:07.510Z,1685136247.510 [Default:CheckIn](INFO): Running loop #14 2023-05-26T21:24:07.510Z,1685136247.510 [Default:CheckIn] Running Loop=14 2023-05-26T21:24:07.511Z,1685136247.511 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:24:07.511Z,1685136247.511 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:24:09.491Z,1685136249.491 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212408.00,A,3648.14830,N,12147.25166,W,2.469,10.21,260523,,,D*4F 2023-05-26T21:24:09.493Z,1685136249.493 [NAL9602](INFO): GPS fix at 20230526T212408: (36.802472, -121.787528) 2023-05-26T21:24:09.540Z,1685136249.540 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:24:09.541Z,1685136249.541 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:24:20.215Z,1685136260.215 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230526T204303/Courier0021.lzma 2023-05-26T21:24:21.217Z,1685136261.217 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0021.lzma.bak 2023-05-26T21:24:21.218Z,1685136261.218 [DataOverHttps](INFO): SBD MOMSN=18363303 2023-05-26T21:24:25.658Z,1685136265.658 [NAL9602](INFO): SBD MO Status=0, MOMSN=26236, MT Status=0, MTMSN=0 2023-05-26T21:24:25.658Z,1685136265.658 [NAL9602](INFO): No messages in MT queue 2023-05-26T21:24:37.583Z,1685136277.583 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230526T204303/Express0022.lzma 2023-05-26T21:24:38.585Z,1685136278.585 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0022.lzma.bak 2023-05-26T21:24:38.585Z,1685136278.585 [DataOverHttps](INFO): SBD MOMSN=18363306 2023-05-26T21:24:39.833Z,1685136279.833 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:24:39.833Z,1685136279.833 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:24:39.833Z,1685136279.833 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:24:56.368Z,1685136296.368 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:29:40.382Z,1685136580.382 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:29:40.382Z,1685136580.382 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:29:40.382Z,1685136580.382 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:29:40.383Z,1685136580.383 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:29:40.788Z,1685136580.788 [Default:CheckIn:D] Stopped 2023-05-26T21:29:40.788Z,1685136580.788 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:29:41.195Z,1685136581.195 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.523836 min 2023-05-26T21:29:41.195Z,1685136581.195 [Default:CheckIn:E] Stopped 2023-05-26T21:29:41.195Z,1685136581.195 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:29:41.195Z,1685136581.195 [Default:CheckIn] Stopped 2023-05-26T21:29:41.196Z,1685136581.196 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:29:41.196Z,1685136581.196 [Default:CheckIn](INFO): Running loop #15 2023-05-26T21:29:41.196Z,1685136581.196 [Default:CheckIn] Running Loop=15 2023-05-26T21:29:41.196Z,1685136581.196 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:29:41.196Z,1685136581.196 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:29:43.201Z,1685136583.201 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212942.00,A,3648.17917,N,12147.27994,W,0.544,273.30,260523,,,D*77 2023-05-26T21:29:43.203Z,1685136583.203 [NAL9602](INFO): GPS fix at 20230526T212942: (36.802986, -121.787999) 2023-05-26T21:29:43.232Z,1685136583.232 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:29:43.232Z,1685136583.232 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:29:50.791Z,1685136590.791 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230526T204303/Courier0024.lzma 2023-05-26T21:29:51.793Z,1685136591.793 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0024.lzma.bak 2023-05-26T21:29:51.793Z,1685136591.793 [DataOverHttps](INFO): SBD MOMSN=18363399 2023-05-26T21:30:03.002Z,1685136603.002 [NAL9602](INFO): SBD MO Status=0, MOMSN=26237, MT Status=0, MTMSN=0 2023-05-26T21:30:03.002Z,1685136603.002 [NAL9602](INFO): No messages in MT queue 2023-05-26T21:30:11.195Z,1685136611.195 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230526T204303/Express0025.lzma 2023-05-26T21:30:12.197Z,1685136612.197 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0025.lzma.bak 2023-05-26T21:30:12.198Z,1685136612.198 [DataOverHttps](INFO): SBD MOMSN=18363402 2023-05-26T21:30:13.536Z,1685136613.536 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:30:13.536Z,1685136613.536 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:30:13.536Z,1685136613.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:30:33.701Z,1685136633.701 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:35:14.093Z,1685136914.093 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:35:14.093Z,1685136914.093 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:35:14.093Z,1685136914.093 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:35:14.093Z,1685136914.093 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:35:14.495Z,1685136914.495 [Default:CheckIn:D] Stopped 2023-05-26T21:35:14.495Z,1685136914.495 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:35:14.895Z,1685136914.895 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.085628 min 2023-05-26T21:35:14.895Z,1685136914.895 [Default:CheckIn:E] Stopped 2023-05-26T21:35:14.896Z,1685136914.895 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:35:14.896Z,1685136914.896 [Default:CheckIn] Stopped 2023-05-26T21:35:14.896Z,1685136914.896 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:35:14.896Z,1685136914.896 [Default:CheckIn](INFO): Running loop #16 2023-05-26T21:35:14.896Z,1685136914.896 [Default:CheckIn] Running Loop=16 2023-05-26T21:35:14.896Z,1685136914.896 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:35:14.896Z,1685136914.896 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:35:16.911Z,1685136916.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213516.00,A,3648.18070,N,12147.27787,W,0.194,208.92,260523,,,A*78 2023-05-26T21:35:16.920Z,1685136916.920 [NAL9602](INFO): GPS fix at 20230526T213516: (36.803012, -121.787965) 2023-05-26T21:35:16.932Z,1685136916.932 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:35:16.932Z,1685136916.932 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:35:24.611Z,1685136924.611 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230526T204303/Courier0027.lzma 2023-05-26T21:35:25.613Z,1685136925.613 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0027.lzma.bak 2023-05-26T21:35:25.613Z,1685136925.613 [DataOverHttps](INFO): SBD MOMSN=18363496 2023-05-26T21:35:41.906Z,1685136941.906 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230526T204303/Express0028.lzma 2023-05-26T21:35:42.906Z,1685136942.906 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0028.lzma.bak 2023-05-26T21:35:42.906Z,1685136942.906 [DataOverHttps](INFO): SBD MOMSN=18363499 2023-05-26T21:35:44.397Z,1685136944.397 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:35:44.397Z,1685136944.397 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:35:44.397Z,1685136944.397 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:36:17.505Z,1685136977.505 [NAL9602](INFO): SBD MO Status=0, MOMSN=26238, MT Status=0, MTMSN=0 2023-05-26T21:36:17.505Z,1685136977.505 [NAL9602](INFO): No messages in MT queue 2023-05-26T21:36:48.208Z,1685137008.208 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:38:05.779Z,1685137085.779 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2023-05-26T21:40:44.967Z,1685137244.967 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:40:44.967Z,1685137244.967 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:40:44.967Z,1685137244.967 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:40:44.968Z,1685137244.968 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:40:45.367Z,1685137245.367 [Default:CheckIn:D] Stopped 2023-05-26T21:40:45.367Z,1685137245.367 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:40:45.797Z,1685137245.797 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.600171 min 2023-05-26T21:40:45.797Z,1685137245.797 [Default:CheckIn:E] Stopped 2023-05-26T21:40:45.798Z,1685137245.798 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:40:45.798Z,1685137245.798 [Default:CheckIn] Stopped 2023-05-26T21:40:45.798Z,1685137245.798 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:40:45.798Z,1685137245.798 [Default:CheckIn](INFO): Running loop #17 2023-05-26T21:40:45.798Z,1685137245.798 [Default:CheckIn] Running Loop=17 2023-05-26T21:40:45.798Z,1685137245.798 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:40:45.798Z,1685137245.798 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:40:47.781Z,1685137247.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214047.00,A,3648.17552,N,12147.27836,W,0.194,280.59,260523,,,A*76 2023-05-26T21:40:47.784Z,1685137247.784 [NAL9602](INFO): GPS fix at 20230526T214047: (36.802925, -121.787973) 2023-05-26T21:40:47.830Z,1685137247.830 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:40:47.830Z,1685137247.830 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:40:55.679Z,1685137255.679 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230526T204303/Courier0030.lzma 2023-05-26T21:40:56.681Z,1685137256.681 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0030.lzma.bak 2023-05-26T21:40:56.682Z,1685137256.682 [DataOverHttps](INFO): SBD MOMSN=18363593 2023-05-26T21:41:12.903Z,1685137272.903 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230526T204303/Express0031.lzma 2023-05-26T21:41:13.905Z,1685137273.905 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0031.lzma.bak 2023-05-26T21:41:13.906Z,1685137273.906 [DataOverHttps](INFO): SBD MOMSN=18363596 2023-05-26T21:41:15.275Z,1685137275.275 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:41:15.275Z,1685137275.275 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:41:15.276Z,1685137275.276 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:41:26.166Z,1685137286.166 [NAL9602](INFO): SBD MO Status=0, MOMSN=26239, MT Status=0, MTMSN=0 2023-05-26T21:41:26.166Z,1685137286.166 [NAL9602](INFO): No messages in MT queue 2023-05-26T21:41:56.870Z,1685137316.870 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:46:15.843Z,1685137575.843 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:46:15.843Z,1685137575.843 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:46:15.843Z,1685137575.843 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:46:15.844Z,1685137575.844 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:46:16.243Z,1685137576.243 [Default:CheckIn:D] Stopped 2023-05-26T21:46:16.243Z,1685137576.243 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:46:16.659Z,1685137576.659 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.114762 min 2023-05-26T21:46:16.659Z,1685137576.659 [Default:CheckIn:E] Stopped 2023-05-26T21:46:16.659Z,1685137576.659 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:46:16.659Z,1685137576.659 [Default:CheckIn] Stopped 2023-05-26T21:46:16.659Z,1685137576.659 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:46:16.659Z,1685137576.659 [Default:CheckIn](INFO): Running loop #18 2023-05-26T21:46:16.660Z,1685137576.660 [Default:CheckIn] Running Loop=18 2023-05-26T21:46:16.660Z,1685137576.660 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:46:16.660Z,1685137576.660 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:46:18.657Z,1685137578.657 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214617.00,A,3648.16853,N,12147.28136,W,0.117,118.05,260523,,,A*7E 2023-05-26T21:46:18.660Z,1685137578.660 [NAL9602](INFO): GPS fix at 20230526T214617: (36.802809, -121.788023) 2023-05-26T21:46:18.671Z,1685137578.671 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:46:18.671Z,1685137578.671 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:46:26.349Z,1685137586.349 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230526T204303/Courier0033.lzma 2023-05-26T21:46:27.349Z,1685137587.349 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0033.lzma.bak 2023-05-26T21:46:27.349Z,1685137587.349 [DataOverHttps](INFO): SBD MOMSN=18363689 2023-05-26T21:46:43.659Z,1685137603.659 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230526T204303/Express0034.lzma 2023-05-26T21:46:44.661Z,1685137604.661 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0034.lzma.bak 2023-05-26T21:46:44.661Z,1685137604.661 [DataOverHttps](INFO): SBD MOMSN=18363692 2023-05-26T21:46:46.166Z,1685137606.166 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:46:46.166Z,1685137606.166 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:46:46.166Z,1685137606.166 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:51:21.252Z,1685137881.252 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-05-26T21:51:46.714Z,1685137906.714 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:51:46.714Z,1685137906.714 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:51:46.714Z,1685137906.714 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:51:46.715Z,1685137906.715 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:51:47.119Z,1685137907.119 [Default:CheckIn:D] Stopped 2023-05-26T21:51:47.119Z,1685137907.119 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:51:47.554Z,1685137907.554 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.629370 min 2023-05-26T21:51:47.554Z,1685137907.554 [Default:CheckIn:E] Stopped 2023-05-26T21:51:47.555Z,1685137907.555 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:51:47.555Z,1685137907.555 [Default:CheckIn] Stopped 2023-05-26T21:51:47.555Z,1685137907.555 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:51:47.555Z,1685137907.555 [Default:CheckIn](INFO): Running loop #19 2023-05-26T21:51:47.555Z,1685137907.555 [Default:CheckIn] Running Loop=19 2023-05-26T21:51:47.555Z,1685137907.555 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:51:47.556Z,1685137907.556 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:51:49.534Z,1685137909.534 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215148.00,A,3648.16763,N,12147.28787,W,0.719,118.05,260523,,,A*7A 2023-05-26T21:51:49.537Z,1685137909.537 [NAL9602](INFO): GPS fix at 20230526T215148: (36.802794, -121.788131) 2023-05-26T21:51:49.584Z,1685137909.584 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:51:49.584Z,1685137909.584 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:51:59.723Z,1685137919.723 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230526T204303/Courier0036.lzma 2023-05-26T21:52:00.725Z,1685137920.725 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0036.lzma.bak 2023-05-26T21:52:00.725Z,1685137920.725 [DataOverHttps](INFO): SBD MOMSN=18363788 2023-05-26T21:52:17.136Z,1685137937.136 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20230526T204303/Express0037.lzma 2023-05-26T21:52:18.137Z,1685137938.137 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0037.lzma.bak 2023-05-26T21:52:18.138Z,1685137938.138 [DataOverHttps](INFO): SBD MOMSN=18363791 2023-05-26T21:52:19.523Z,1685137939.523 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:52:19.523Z,1685137939.523 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:52:19.524Z,1685137939.524 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:52:21.508Z,1685137941.508 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T21:56:15.456Z,1685138175.456 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-05-26T21:56:15.456Z,1685138175.456 [DropWeight] Hardware Fault, FailCount= 1 2023-05-26T21:56:15.456Z,1685138175.456 [DropWeight](ERROR): Hardware Fault 2023-05-26T21:56:15.515Z,1685138175.515 [CommandExec](FAULT): Scheduling is paused 2023-05-26T21:56:15.516Z,1685138175.516 [CBIT](INFO): Critical error at 20230526T215615 2023-05-26T21:56:15.518Z,1685138175.518 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-05-26T21:56:15.519Z,1685138175.519 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-05-26T21:56:15.859Z,1685138175.859 [CBIT](INFO): Critical error at 20230526T215615 2023-05-26T21:57:20.092Z,1685138240.092 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T21:57:20.092Z,1685138240.092 [Default:CheckIn:C.Wait] Stopped 2023-05-26T21:57:20.093Z,1685138240.093 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T21:57:20.125Z,1685138240.125 [Default:CheckIn:D] Running Loop=1 2023-05-26T21:57:20.499Z,1685138240.499 [Default:CheckIn:D] Stopped 2023-05-26T21:57:20.499Z,1685138240.499 [Default:CheckIn:E] Running Loop=1 2023-05-26T21:57:20.888Z,1685138240.888 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.185693 min 2023-05-26T21:57:20.888Z,1685138240.888 [Default:CheckIn:E] Stopped 2023-05-26T21:57:20.888Z,1685138240.888 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T21:57:20.888Z,1685138240.888 [Default:CheckIn] Stopped 2023-05-26T21:57:20.889Z,1685138240.889 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T21:57:20.889Z,1685138240.889 [Default:CheckIn](INFO): Running loop #20 2023-05-26T21:57:20.889Z,1685138240.889 [Default:CheckIn] Running Loop=20 2023-05-26T21:57:20.889Z,1685138240.889 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T21:57:20.889Z,1685138240.889 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T21:57:22.894Z,1685138242.894 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215722.00,A,3648.16874,N,12147.28337,W,0.447,0.00,260523,,,A*73 2023-05-26T21:57:22.896Z,1685138242.896 [NAL9602](INFO): GPS fix at 20230526T215722: (36.802812, -121.788056) 2023-05-26T21:57:22.907Z,1685138242.907 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T21:57:22.907Z,1685138242.907 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T21:57:31.119Z,1685138251.119 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20230526T204303/Courier0039.lzma 2023-05-26T21:57:32.121Z,1685138252.121 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0039.lzma.bak 2023-05-26T21:57:32.122Z,1685138252.122 [DataOverHttps](INFO): SBD MOMSN=18363900 2023-05-26T21:57:48.320Z,1685138268.320 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20230526T204303/Express0040.lzma 2023-05-26T21:57:49.321Z,1685138269.321 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0040.lzma.bak 2023-05-26T21:57:49.322Z,1685138269.322 [DataOverHttps](INFO): SBD MOMSN=18363905 2023-05-26T21:57:50.793Z,1685138270.793 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T21:57:50.793Z,1685138270.793 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T21:57:50.793Z,1685138270.793 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T21:57:53.597Z,1685138273.597 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-05-26T21:57:53.679Z,1685138273.679 [NAL9602](ERROR): received: +CSQ:0 OK239, 0, 0, 0, 0 OK 2023-05-26T22:02:25.493Z,1685138545.493 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-05-26T22:02:51.359Z,1685138571.359 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T22:02:51.359Z,1685138571.359 [Default:CheckIn:C.Wait] Stopped 2023-05-26T22:02:51.359Z,1685138571.359 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T22:02:51.360Z,1685138571.360 [Default:CheckIn:D] Running Loop=1 2023-05-26T22:02:51.808Z,1685138571.808 [Default:CheckIn:D] Stopped 2023-05-26T22:02:51.808Z,1685138571.808 [Default:CheckIn:E] Running Loop=1 2023-05-26T22:02:52.164Z,1685138572.164 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.707511 min 2023-05-26T22:02:52.164Z,1685138572.164 [Default:CheckIn:E] Stopped 2023-05-26T22:02:52.164Z,1685138572.164 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T22:02:52.165Z,1685138572.165 [Default:CheckIn] Stopped 2023-05-26T22:02:52.165Z,1685138572.165 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T22:02:52.165Z,1685138572.165 [Default:CheckIn](INFO): Running loop #21 2023-05-26T22:02:52.165Z,1685138572.165 [Default:CheckIn] Running Loop=21 2023-05-26T22:02:52.165Z,1685138572.165 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T22:02:52.165Z,1685138572.165 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T22:02:54.174Z,1685138574.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220253.00,A,3648.17340,N,12147.27162,W,0.233,191.62,260523,,,A*7E 2023-05-26T22:02:54.177Z,1685138574.177 [NAL9602](INFO): GPS fix at 20230526T220253: (36.802890, -121.787860) 2023-05-26T22:02:54.192Z,1685138574.192 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T22:02:54.192Z,1685138574.192 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T22:03:01.763Z,1685138581.763 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230526T204303/Courier0042.lzma 2023-05-26T22:03:02.765Z,1685138582.765 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0042.lzma.bak 2023-05-26T22:03:02.765Z,1685138582.765 [DataOverHttps](INFO): SBD MOMSN=18364007 2023-05-26T22:03:19.567Z,1685138599.567 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230526T204303/Express0043.lzma 2023-05-26T22:03:20.569Z,1685138600.569 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0043.lzma.bak 2023-05-26T22:03:20.570Z,1685138600.570 [DataOverHttps](INFO): SBD MOMSN=18364010 2023-05-26T22:03:22.057Z,1685138602.057 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T22:03:22.057Z,1685138602.057 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T22:03:22.057Z,1685138602.057 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T22:03:26.512Z,1685138606.512 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-26T22:07:30.163Z,1685138850.163 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-05-26T22:07:56.427Z,1685138876.427 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-05-26T22:08:21.520Z,1685138901.520 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-05-26T22:08:22.646Z,1685138902.646 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-26T22:08:22.646Z,1685138902.646 [Default:CheckIn:C.Wait] Stopped 2023-05-26T22:08:22.646Z,1685138902.646 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T22:08:22.647Z,1685138902.647 [Default:CheckIn:D] Running Loop=1 2023-05-26T22:08:23.044Z,1685138903.044 [Default:CheckIn:D] Stopped 2023-05-26T22:08:23.044Z,1685138903.044 [Default:CheckIn:E] Running Loop=1 2023-05-26T22:08:23.496Z,1685138903.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.228125 min 2023-05-26T22:08:23.496Z,1685138903.496 [Default:CheckIn:E] Stopped 2023-05-26T22:08:23.496Z,1685138903.496 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-26T22:08:23.496Z,1685138903.496 [Default:CheckIn] Stopped 2023-05-26T22:08:23.496Z,1685138903.496 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T22:08:23.496Z,1685138903.496 [Default:CheckIn](INFO): Running loop #22 2023-05-26T22:08:23.496Z,1685138903.496 [Default:CheckIn] Running Loop=22 2023-05-26T22:08:23.497Z,1685138903.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-26T22:08:23.497Z,1685138903.497 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-26T22:08:25.472Z,1685138905.472 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220824.00,A,3648.16816,N,12147.28676,W,0.486,125.85,260523,,,A*7E 2023-05-26T22:08:25.474Z,1685138905.474 [NAL9602](INFO): GPS fix at 20230526T220824: (36.802803, -121.788113) 2023-05-26T22:08:25.536Z,1685138905.536 [Default:CheckIn:Read_GPS] Stopped 2023-05-26T22:08:25.536Z,1685138905.536 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-26T22:08:32.408Z,1685138912.408 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-05-26T22:08:33.389Z,1685138913.389 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20230526T204303/Courier0045.lzma 2023-05-26T22:08:34.389Z,1685138914.389 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Courier0045.lzma.bak 2023-05-26T22:08:34.390Z,1685138914.390 [DataOverHttps](INFO): SBD MOMSN=18364103 2023-05-26T22:08:43.355Z,1685138923.355 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-05-26T22:08:50.995Z,1685138930.995 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230526T204303/Express0046.lzma 2023-05-26T22:08:51.998Z,1685138931.998 [DataOverHttps](INFO): Moved sent file to Logs/20230526T204303/Express0046.lzma.bak 2023-05-26T22:08:51.998Z,1685138931.998 [DataOverHttps](INFO): SBD MOMSN=18364106 2023-05-26T22:08:52.174Z,1685138932.174 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-05-26T22:08:53.345Z,1685138933.345 [Default:CheckIn:Read_Iridium] Stopped 2023-05-26T22:08:53.345Z,1685138933.345 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-26T22:08:53.345Z,1685138933.345 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-26T22:08:54.210Z,1685138934.210 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-05-26T22:08:56.161Z,1685138936.161 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-05-26T22:08:56.239Z,1685138936.239 [NAL9602](ERROR): received: +CSQ:0 OK239, 0, 0, 0, 0 OK 2023-05-26T22:09:05.113Z,1685138945.113 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-05-26T22:09:08.448Z,1685138948.448 [CommandExec](IMPORTANT): got command quit 2023-05-26T22:09:09.452Z,1685138949.452 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:09.453Z,1685138949.453 [CommandExec](INFO): Uninitializing the command executive. 2023-05-26T22:09:09.453Z,1685138949.453 [CommandExec](INFO): Uninitializing the command scheduler. 2023-05-26T22:09:09.454Z,1685138949.454 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:09.583Z,1685138949.583 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-05-26T22:09:09.584Z,1685138949.584 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-05-26T22:09:09.584Z,1685138949.584 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:09.584Z,1685138949.584 [NavChartDb](INFO): Join timeout helper Thread ID is 9270 2023-05-26T22:09:09.816Z,1685138949.816 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:09.816Z,1685138949.816 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:09.827Z,1685138949.827 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2023-05-26T22:09:09.827Z,1685138949.827 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:09.828Z,1685138949.828 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 9271 2023-05-26T22:09:10.092Z,1685138950.092 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:10.092Z,1685138950.092 [WetLabsUBAT](INFO): Powering down 2023-05-26T22:09:10.093Z,1685138950.093 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:10.099Z,1685138950.099 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-05-26T22:09:10.100Z,1685138950.100 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:10.100Z,1685138950.100 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9272 2023-05-26T22:09:10.180Z,1685138950.180 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:10.180Z,1685138950.180 [WetLabsBB2FL](INFO): Powering down 2023-05-26T22:09:10.181Z,1685138950.181 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:10.183Z,1685138950.183 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-05-26T22:09:10.183Z,1685138950.183 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:10.184Z,1685138950.184 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9273 2023-05-26T22:09:10.596Z,1685138950.596 [CTD_Seabird](INFO): Powering down 2023-05-26T22:09:10.607Z,1685138950.607 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:10.608Z,1685138950.608 [CTD_Seabird](INFO): Powering down 2023-05-26T22:09:10.625Z,1685138950.625 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:10.636Z,1685138950.636 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-05-26T22:09:10.636Z,1685138950.636 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:10.636Z,1685138950.636 [DAT](INFO): Join timeout helper Thread ID is 9274 2023-05-26T22:09:10.816Z,1685138950.816 [DAT](INFO): Powering down 2023-05-26T22:09:10.888Z,1685138950.888 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:10.888Z,1685138950.888 [DAT](INFO): Powering down 2023-05-26T22:09:10.889Z,1685138950.889 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:10.904Z,1685138950.904 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-05-26T22:09:10.904Z,1685138950.904 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:10.905Z,1685138950.905 [Radio_Surface](INFO): Join timeout helper Thread ID is 9275 2023-05-26T22:09:11.296Z,1685138951.296 [Radio_Surface](INFO): Powering down 2023-05-26T22:09:11.297Z,1685138951.297 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:11.297Z,1685138951.297 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:11.308Z,1685138951.308 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-05-26T22:09:11.308Z,1685138951.308 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:11.309Z,1685138951.309 [Onboard](INFO): Join timeout helper Thread ID is 9276 2023-05-26T22:09:11.700Z,1685138951.700 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-05-26T22:09:15.312Z,1685138955.312 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:15.312Z,1685138955.312 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.320Z,1685138955.320 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-05-26T22:09:15.320Z,1685138955.320 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.321Z,1685138955.321 [DataOverHttps](INFO): Join timeout helper Thread ID is 9277 2023-05-26T22:09:15.716Z,1685138955.716 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:15.716Z,1685138955.716 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.738Z,1685138955.738 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-05-26T22:09:15.738Z,1685138955.738 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.739Z,1685138955.739 [BackseatComponent](INFO): Join timeout helper Thread ID is 9278 2023-05-26T22:09:15.872Z,1685138955.872 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:15.872Z,1685138955.872 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.892Z,1685138955.892 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-05-26T22:09:15.892Z,1685138955.892 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.893Z,1685138955.893 [logger](INFO): Join timeout helper Thread ID is 9279 2023-05-26T22:09:15.932Z,1685138955.932 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:15.932Z,1685138955.932 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.933Z,1685138955.933 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-05-26T22:09:15.933Z,1685138955.933 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.934Z,1685138955.934 [CommandLine](INFO): Join timeout helper Thread ID is 9280 2023-05-26T22:09:15.948Z,1685138955.948 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:15.948Z,1685138955.948 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.960Z,1685138955.960 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-05-26T22:09:15.960Z,1685138955.960 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.961Z,1685138955.961 [CommandExec](INFO): Join timeout helper Thread ID is 9281 2023-05-26T22:09:15.974Z,1685138955.974 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-05-26T22:09:15.974Z,1685138955.974 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:15.974Z,1685138955.974 [controlThread](INFO): Join timeout helper Thread ID is 9282 2023-05-26T22:09:16.026Z,1685138956.026 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-05-26T22:09:16.060Z,1685138956.060 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-26T22:09:16.061Z,1685138956.061 [controlThread](DEBUG): Uninitializing ControlThread 2023-05-26T22:09:16.061Z,1685138956.061 [AHRS_M2](INFO): Powering down 2023-05-26T22:09:16.133Z,1685138956.133 [NAL9602](INFO): Powering down 2023-05-26T22:09:16.204Z,1685138956.204 [RDI_Pathfinder](INFO): Powering down 2023-05-26T22:09:16.205Z,1685138956.205 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-05-26T22:09:16.206Z,1685138956.206 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-05-26T22:09:16.206Z,1685138956.206 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-05-26T22:09:16.207Z,1685138956.207 [MissionManager](INFO): Uninitializing Mission Default 2023-05-26T22:09:16.207Z,1685138956.207 [Default] Stopped 2023-05-26T22:09:16.207Z,1685138956.207 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-26T22:09:16.207Z,1685138956.207 [Default:B.GoToSurface] Stopped 2023-05-26T22:09:16.207Z,1685138956.207 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-26T22:09:16.207Z,1685138956.207 [Default:CheckIn] Stopped 2023-05-26T22:09:16.207Z,1685138956.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-26T22:09:16.207Z,1685138956.207 [Default:CheckIn:C.Wait] Stopped 2023-05-26T22:09:16.208Z,1685138956.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-26T22:09:16.210Z,1685138956.210 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-05-26T22:09:16.210Z,1685138956.210 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-05-26T22:09:16.211Z,1685138956.211 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-05-26T22:09:16.211Z,1685138956.211 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-05-26T22:09:16.211Z,1685138956.211 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-05-26T22:09:16.211Z,1685138956.211 [BuoyancyServo](INFO): Powering down 2023-05-26T22:09:16.224Z,1685138956.224 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-05-26T22:09:16.224Z,1685138956.224 [ElevatorServo](INFO): Powering down 2023-05-26T22:09:16.225Z,1685138956.225 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-05-26T22:09:16.225Z,1685138956.225 [MassServo](INFO): Powering down 2023-05-26T22:09:16.225Z,1685138956.225 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-26T22:09:16.226Z,1685138956.226 [RudderServo](INFO): Powering down 2023-05-26T22:09:16.226Z,1685138956.226 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-05-26T22:09:16.226Z,1685138956.226 [ThrusterServo](INFO): Powering down 2023-05-26T22:09:16.227Z,1685138956.227 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-05-26T22:09:16.228Z,1685138956.228 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-05-26T22:09:16.228Z,1685138956.228 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-05-26T22:09:16.228Z,1685138956.228 [CBIT](DEBUG): Powering off loads. 2023-05-26T22:09:16.239Z,1685138956.239 [CBIT](DEBUG): Disabling WDT. 2023-05-26T22:09:16.251Z,1685138956.251 [CBIT](DEBUG): Opening all GF detection circuits. 2023-05-26T22:09:16.252Z,1685138956.252 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.320Z,1685138956.320 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.327Z,1685138956.327 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.329Z,1685138956.329 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.335Z,1685138956.335 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.445Z,1685138956.445 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.510Z,1685138956.510 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.512Z,1685138956.512 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.516Z,1685138956.516 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.523Z,1685138956.523 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-26T22:09:16.620Z,1685138956.620 [logger ThreadHandler](INFO): Thread cancelled.