2023-03-27T23:19:36.984Z,1679959176.984 [CommandExec](IMPORTANT): got command restart logs 2023-03-27T23:19:55.604Z,1679959195.604 [CommandExec](IMPORTANT): got command get NAL9602.sigQuality 2023-03-27T23:19:55.604Z,1679959195.604 [CommandExec](IMPORTANT): NAL9602.sigQuality 0 count 2023-03-27T23:20:08.460Z,1679959208.460 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-27T23:20:15.293Z,1679959215.293 [NAL9602](INFO): SBD MO Status=0, MOMSN=39531, MT Status=0, MTMSN=0 2023-03-27T23:20:15.294Z,1679959215.294 [NAL9602](INFO): No messages in MT queue 2023-03-27T23:20:45.000Z,1679959246.000 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-27T23:20:46.058Z,1679959246.058 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:21:19.145Z,1679959279.145 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-27T23:21:19.145Z,1679959279.145 [Default:CheckIn:C.Wait] Stopped 2023-03-27T23:21:19.145Z,1679959279.145 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-27T23:21:19.146Z,1679959279.146 [Default:CheckIn:D] Running Loop=1 2023-03-27T23:21:19.552Z,1679959279.552 [Default:CheckIn:D] Stopped 2023-03-27T23:21:19.552Z,1679959279.552 [Default:CheckIn:E] Running Loop=1 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 310.104590 min 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn:E] Stopped 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn] Stopped 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn](INFO): Running loop #35 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn] Running Loop=35 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-27T23:21:19.942Z,1679959279.942 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-27T23:21:21.958Z,1679959281.958 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232121.00,A,3648.17572,N,12147.28367,W,0.097,238.39,270323,,,A*71 2023-03-27T23:21:21.960Z,1679959281.960 [NAL9602](INFO): GPS fix at 20230327T232121: (36.802929, -121.788061) 2023-03-27T23:21:21.972Z,1679959281.972 [Default:CheckIn:Read_GPS] Stopped 2023-03-27T23:21:21.972Z,1679959281.972 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-27T23:21:28.894Z,1679959288.894 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230327T180650/Courier0106.lzma 2023-03-27T23:21:29.897Z,1679959289.897 [DataOverHttps](INFO): Moved sent file to Logs/20230327T180650/Courier0106.lzma.bak 2023-03-27T23:21:29.897Z,1679959289.897 [DataOverHttps](INFO): SBD MOMSN=17694183 2023-03-27T23:21:45.762Z,1679959305.762 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20230327T231936/Courier0000.lzma 2023-03-27T23:21:46.764Z,1679959306.764 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0000.lzma.bak 2023-03-27T23:21:46.765Z,1679959306.765 [DataOverHttps](INFO): SBD MOMSN=17694187 2023-03-27T23:21:56.383Z,1679959316.383 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:22:03.222Z,1679959323.222 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230327T180650/Express0107.lzma 2023-03-27T23:22:04.225Z,1679959324.225 [DataOverHttps](INFO): Moved sent file to Logs/20230327T180650/Express0107.lzma.bak 2023-03-27T23:22:04.225Z,1679959324.225 [DataOverHttps](INFO): SBD MOMSN=17694195 2023-03-27T23:22:05.219Z,1679959325.219 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:22:13.663Z,1679959333.663 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-03-27T23:22:13.664Z,1679959333.664 [DropWeight] Hardware Fault, FailCount= 1 2023-03-27T23:22:13.664Z,1679959333.664 [DropWeight](ERROR): Hardware Fault 2023-03-27T23:22:13.748Z,1679959333.748 [CBIT](INFO): Critical error at 20230327T232213 2023-03-27T23:22:13.755Z,1679959333.755 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-03-27T23:22:13.756Z,1679959333.756 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-03-27T23:22:14.110Z,1679959334.110 [CBIT](INFO): Critical error at 20230327T232213 2023-03-27T23:22:21.759Z,1679959341.759 [DataOverHttps](INFO): Sending 425 bytes from file Logs/20230327T231936/Express0001.lzma 2023-03-27T23:22:22.760Z,1679959342.760 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0001.lzma.bak 2023-03-27T23:22:22.761Z,1679959342.761 [DataOverHttps](INFO): SBD MOMSN=17694197 2023-03-27T23:22:24.215Z,1679959344.215 [Default:CheckIn:Read_Iridium] Stopped 2023-03-27T23:22:24.215Z,1679959344.215 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-27T23:22:24.215Z,1679959344.215 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-27T23:22:27.179Z,1679959347.179 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:22:35.958Z,1679959355.958 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:22:44.448Z,1679959364.448 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:23:01.824Z,1679959381.824 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:23:13.940Z,1679959393.940 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:23:22.412Z,1679959402.412 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:23:39.780Z,1679959419.780 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-27T23:23:58.333Z,1679959438.333 [NAL9602](INFO): SBD MO Status=2, MOMSN=39532, MT Status=2, MTMSN=0 2023-03-27T23:23:58.333Z,1679959438.333 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-27T23:24:02.823Z,1679959442.823 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:24:14.555Z,1679959454.555 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-27T23:24:25.004Z,1679959465.004 [NAL9602](INFO): SBD MO Status=0, MOMSN=39532, MT Status=0, MTMSN=0 2023-03-27T23:24:25.004Z,1679959465.004 [NAL9602](INFO): No messages in MT queue 2023-03-27T23:24:55.700Z,1679959495.700 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-27T23:24:55.723Z,1679959495.723 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:27:24.785Z,1679959644.785 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-27T23:27:24.785Z,1679959644.785 [Default:CheckIn:C.Wait] Stopped 2023-03-27T23:27:24.785Z,1679959644.785 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-27T23:27:24.785Z,1679959644.785 [Default:CheckIn:D] Running Loop=1 2023-03-27T23:27:25.191Z,1679959645.191 [Default:CheckIn:D] Stopped 2023-03-27T23:27:25.192Z,1679959645.192 [Default:CheckIn:E] Running Loop=1 2023-03-27T23:27:25.601Z,1679959645.601 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 316.198568 min 2023-03-27T23:27:25.601Z,1679959645.601 [Default:CheckIn:E] Stopped 2023-03-27T23:27:25.601Z,1679959645.601 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-27T23:27:25.601Z,1679959645.601 [Default:CheckIn] Stopped 2023-03-27T23:27:25.602Z,1679959645.602 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-27T23:27:25.602Z,1679959645.602 [Default:CheckIn](INFO): Running loop #36 2023-03-27T23:27:25.602Z,1679959645.602 [Default:CheckIn] Running Loop=36 2023-03-27T23:27:25.602Z,1679959645.602 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-27T23:27:25.602Z,1679959645.602 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-27T23:27:27.612Z,1679959647.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232726.00,A,3648.17593,N,12147.28489,W,0.039,0.00,270323,,,D*7A 2023-03-27T23:27:27.614Z,1679959647.614 [NAL9602](INFO): GPS fix at 20230327T232726: (36.802932, -121.788082) 2023-03-27T23:27:27.633Z,1679959647.633 [Default:CheckIn:Read_GPS] Stopped 2023-03-27T23:27:27.633Z,1679959647.633 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-27T23:27:35.610Z,1679959655.610 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20230327T231936/Courier0003.lzma 2023-03-27T23:27:36.779Z,1679959656.779 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:27:37.061Z,1679959657.061 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0003.lzma.bak 2023-03-27T23:27:37.061Z,1679959657.061 [DataOverHttps](INFO): SBD MOMSN=17694239 2023-03-27T23:27:52.362Z,1679959672.362 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230327T231936/Express0004.lzma 2023-03-27T23:27:53.364Z,1679959673.364 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0004.lzma.bak 2023-03-27T23:27:53.365Z,1679959673.365 [DataOverHttps](INFO): SBD MOMSN=17694244 2023-03-27T23:27:54.709Z,1679959674.709 [Default:CheckIn:Read_Iridium] Stopped 2023-03-27T23:27:54.709Z,1679959674.709 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-27T23:27:54.709Z,1679959674.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-27T23:28:04.014Z,1679959684.014 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:28:12.517Z,1679959692.517 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:28:25.472Z,1679959705.472 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-27T23:28:31.845Z,1679959711.845 [NAL9602](INFO): SBD MO Status=0, MOMSN=39533, MT Status=0, MTMSN=0 2023-03-27T23:28:31.845Z,1679959711.845 [NAL9602](INFO): No messages in MT queue 2023-03-27T23:29:02.549Z,1679959742.549 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-27T23:29:02.578Z,1679959742.578 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:32:55.434Z,1679959975.434 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-27T23:32:55.435Z,1679959975.435 [Default:CheckIn:C.Wait] Stopped 2023-03-27T23:32:55.435Z,1679959975.435 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-27T23:32:55.435Z,1679959975.435 [Default:CheckIn:D] Running Loop=1 2023-03-27T23:32:55.835Z,1679959975.835 [Default:CheckIn:D] Stopped 2023-03-27T23:32:55.836Z,1679959975.836 [Default:CheckIn:E] Running Loop=1 2023-03-27T23:32:56.255Z,1679959976.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 321.709310 min 2023-03-27T23:32:56.256Z,1679959976.256 [Default:CheckIn:E] Stopped 2023-03-27T23:32:56.256Z,1679959976.256 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-27T23:32:56.256Z,1679959976.256 [Default:CheckIn] Stopped 2023-03-27T23:32:56.256Z,1679959976.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-27T23:32:56.256Z,1679959976.256 [Default:CheckIn](INFO): Running loop #37 2023-03-27T23:32:56.256Z,1679959976.256 [Default:CheckIn] Running Loop=37 2023-03-27T23:32:56.256Z,1679959976.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-27T23:32:56.256Z,1679959976.256 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-27T23:32:58.249Z,1679959978.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233257.00,A,3648.17600,N,12147.28417,W,0.058,0.00,270323,,,D*71 2023-03-27T23:32:58.252Z,1679959978.252 [NAL9602](INFO): GPS fix at 20230327T233257: (36.802933, -121.788070) 2023-03-27T23:32:58.263Z,1679959978.263 [Default:CheckIn:Read_GPS] Stopped 2023-03-27T23:32:58.263Z,1679959978.263 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-27T23:33:05.546Z,1679959985.546 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230327T231936/Courier0006.lzma 2023-03-27T23:33:06.548Z,1679959986.548 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0006.lzma.bak 2023-03-27T23:33:06.548Z,1679959986.548 [DataOverHttps](INFO): SBD MOMSN=17694580 2023-03-27T23:33:10.073Z,1679959990.073 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:33:15.656Z,1679959995.656 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:33:22.462Z,1679960002.462 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230327T231936/Express0007.lzma 2023-03-27T23:33:23.464Z,1679960003.464 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0007.lzma.bak 2023-03-27T23:33:23.464Z,1679960003.464 [DataOverHttps](INFO): SBD MOMSN=17694583 2023-03-27T23:33:24.582Z,1679960004.582 [Reporter](INFO): NAL9602.sigQuality 1 count 2023-03-27T23:33:24.949Z,1679960004.949 [Default:CheckIn:Read_Iridium] Stopped 2023-03-27T23:33:24.949Z,1679960004.949 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-27T23:33:24.949Z,1679960004.949 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-27T23:33:41.564Z,1679960021.564 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:33:50.429Z,1679960030.429 [Reporter](INFO): NAL9602.sigQuality 4 count 2023-03-27T23:33:52.790Z,1679960032.790 [NAL9602](INFO): SBD MO Status=0, MOMSN=39534, MT Status=0, MTMSN=0 2023-03-27T23:33:52.790Z,1679960032.790 [NAL9602](INFO): No messages in MT queue 2023-03-27T23:34:23.503Z,1679960063.503 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-27T23:34:23.571Z,1679960063.571 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:37:14.044Z,1679960234.044 [CBIT](INFO): Clearing failed state for component DropWeight 2023-03-27T23:37:14.044Z,1679960234.044 [DropWeight] No Fault, FailCount= 1 2023-03-27T23:38:25.575Z,1679960305.575 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-27T23:38:25.576Z,1679960305.576 [Default:CheckIn:C.Wait] Stopped 2023-03-27T23:38:25.576Z,1679960305.576 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-27T23:38:25.576Z,1679960305.576 [Default:CheckIn:D] Running Loop=1 2023-03-27T23:38:25.932Z,1679960305.932 [Default:CheckIn:D] Stopped 2023-03-27T23:38:25.932Z,1679960305.932 [Default:CheckIn:E] Running Loop=1 2023-03-27T23:38:26.350Z,1679960306.350 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 327.210905 min 2023-03-27T23:38:26.350Z,1679960306.350 [Default:CheckIn:E] Stopped 2023-03-27T23:38:26.350Z,1679960306.350 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-27T23:38:26.350Z,1679960306.350 [Default:CheckIn] Stopped 2023-03-27T23:38:26.350Z,1679960306.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-27T23:38:26.351Z,1679960306.351 [Default:CheckIn](INFO): Running loop #38 2023-03-27T23:38:26.351Z,1679960306.351 [Default:CheckIn] Running Loop=38 2023-03-27T23:38:26.351Z,1679960306.351 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-27T23:38:26.351Z,1679960306.351 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-27T23:38:28.349Z,1679960308.349 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233827.00,A,3648.17591,N,12147.28418,W,0.000,0.00,270323,,,D*75 2023-03-27T23:38:28.352Z,1679960308.352 [NAL9602](INFO): GPS fix at 20230327T233827: (36.802932, -121.788070) 2023-03-27T23:38:28.382Z,1679960308.382 [Default:CheckIn:Read_GPS] Stopped 2023-03-27T23:38:28.382Z,1679960308.382 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-27T23:38:35.762Z,1679960315.762 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230327T231936/Courier0009.lzma 2023-03-27T23:38:36.902Z,1679960316.902 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0009.lzma.bak 2023-03-27T23:38:36.902Z,1679960316.902 [DataOverHttps](INFO): SBD MOMSN=17694654 2023-03-27T23:38:38.891Z,1679960318.891 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-27T23:38:49.390Z,1679960329.390 [NAL9602](INFO): SBD MO Status=0, MOMSN=39535, MT Status=0, MTMSN=0 2023-03-27T23:38:49.390Z,1679960329.390 [NAL9602](INFO): No messages in MT queue 2023-03-27T23:38:55.438Z,1679960335.438 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230327T231936/Express0010.lzma 2023-03-27T23:38:56.445Z,1679960336.445 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0010.lzma.bak 2023-03-27T23:38:56.445Z,1679960336.445 [DataOverHttps](INFO): SBD MOMSN=17694658 2023-03-27T23:38:57.941Z,1679960337.941 [Default:CheckIn:Read_Iridium] Stopped 2023-03-27T23:38:57.941Z,1679960337.941 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-27T23:38:57.942Z,1679960337.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-27T23:39:20.130Z,1679960360.130 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-27T23:39:20.185Z,1679960360.185 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:41:18.876Z,1679960478.876 [CommandLine](INFO): End of History 2023-03-27T23:41:18.881Z,1679960478.881 [CommandLine](INFO): End of History 2023-03-27T23:41:18.885Z,1679960478.885 [CommandLine](INFO): End of History 2023-03-27T23:41:18.887Z,1679960478.887 [CommandLine](INFO): End of History 2023-03-27T23:41:18.888Z,1679960478.888 [CommandLine](INFO): End of History 2023-03-27T23:41:18.889Z,1679960478.889 [CommandLine](INFO): End of History 2023-03-27T23:41:18.962Z,1679960478.962 [CommandLine](INFO): End of History 2023-03-27T23:41:18.964Z,1679960478.964 [CommandLine](INFO): End of History 2023-03-27T23:41:18.965Z,1679960478.965 [CommandLine](INFO): End of History 2023-03-27T23:41:18.966Z,1679960478.966 [CommandLine](INFO): End of History 2023-03-27T23:41:18.967Z,1679960478.967 [CommandLine](INFO): End of History 2023-03-27T23:41:18.969Z,1679960478.969 [CommandLine](INFO): End of History 2023-03-27T23:41:18.970Z,1679960478.970 [CommandLine](INFO): End of History 2023-03-27T23:41:18.995Z,1679960478.995 [CommandLine](INFO): End of History 2023-03-27T23:41:18.997Z,1679960478.997 [CommandLine](INFO): End of History 2023-03-27T23:41:18.998Z,1679960478.998 [CommandLine](INFO): End of History 2023-03-27T23:41:19.015Z,1679960479.015 [CommandLine](INFO): End of History 2023-03-27T23:41:19.016Z,1679960479.016 [CommandLine](INFO): End of History 2023-03-27T23:41:19.018Z,1679960479.018 [CommandLine](INFO): End of History 2023-03-27T23:41:19.019Z,1679960479.019 [CommandLine](INFO): End of History 2023-03-27T23:41:19.020Z,1679960479.020 [CommandLine](INFO): End of History 2023-03-27T23:41:19.021Z,1679960479.021 [CommandLine](INFO): End of History 2023-03-27T23:41:19.039Z,1679960479.039 [CommandLine](INFO): End of History 2023-03-27T23:41:19.040Z,1679960479.040 [CommandLine](INFO): End of History 2023-03-27T23:41:19.041Z,1679960479.041 [CommandLine](INFO): End of History 2023-03-27T23:41:19.043Z,1679960479.043 [CommandLine](INFO): End of History 2023-03-27T23:43:58.531Z,1679960638.531 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-27T23:43:58.532Z,1679960638.532 [Default:CheckIn:C.Wait] Stopped 2023-03-27T23:43:58.532Z,1679960638.532 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-27T23:43:58.532Z,1679960638.532 [Default:CheckIn:D] Running Loop=1 2023-03-27T23:43:58.926Z,1679960638.926 [Default:CheckIn:D] Stopped 2023-03-27T23:43:58.926Z,1679960638.926 [Default:CheckIn:E] Running Loop=1 2023-03-27T23:43:59.331Z,1679960639.331 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 332.760807 min 2023-03-27T23:43:59.331Z,1679960639.331 [Default:CheckIn:E] Stopped 2023-03-27T23:43:59.331Z,1679960639.331 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-27T23:43:59.331Z,1679960639.331 [Default:CheckIn] Stopped 2023-03-27T23:43:59.331Z,1679960639.331 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-27T23:43:59.332Z,1679960639.332 [Default:CheckIn](INFO): Running loop #39 2023-03-27T23:43:59.332Z,1679960639.332 [Default:CheckIn] Running Loop=39 2023-03-27T23:43:59.332Z,1679960639.332 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-27T23:43:59.332Z,1679960639.332 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-27T23:44:01.343Z,1679960641.343 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234400.00,A,3648.17599,N,12147.28303,W,0.039,0.00,270323,,,D*74 2023-03-27T23:44:01.345Z,1679960641.345 [NAL9602](INFO): GPS fix at 20230327T234400: (36.802933, -121.788050) 2023-03-27T23:44:01.356Z,1679960641.356 [Default:CheckIn:Read_GPS] Stopped 2023-03-27T23:44:01.357Z,1679960641.357 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-27T23:44:08.238Z,1679960648.238 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230327T231936/Courier0012.lzma 2023-03-27T23:44:08.756Z,1679960648.756 [Reporter](INFO): NAL9602.sigQuality 5 count 2023-03-27T23:44:09.240Z,1679960649.240 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0012.lzma.bak 2023-03-27T23:44:09.241Z,1679960649.241 [DataOverHttps](INFO): SBD MOMSN=17694992 2023-03-27T23:44:22.418Z,1679960662.418 [NAL9602](INFO): SBD MO Status=2, MOMSN=39536, MT Status=2, MTMSN=0 2023-03-27T23:44:22.418Z,1679960662.418 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-27T23:44:26.637Z,1679960666.637 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-27T23:44:28.150Z,1679960668.150 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230327T231936/Express0013.lzma 2023-03-27T23:44:29.152Z,1679960669.152 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0013.lzma.bak 2023-03-27T23:44:29.153Z,1679960669.153 [DataOverHttps](INFO): SBD MOMSN=17694995 2023-03-27T23:44:30.549Z,1679960670.549 [Default:CheckIn:Read_Iridium] Stopped 2023-03-27T23:44:30.550Z,1679960670.550 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-27T23:44:30.550Z,1679960670.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-27T23:44:33.768Z,1679960673.768 [NAL9602](INFO): SBD MO Status=0, MOMSN=39536, MT Status=0, MTMSN=0 2023-03-27T23:44:33.769Z,1679960673.769 [NAL9602](INFO): No messages in MT queue 2023-03-27T23:45:04.484Z,1679960704.484 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-27T23:45:04.598Z,1679960704.598 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:49:17.380Z,1679960957.380 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:49:31.133Z,1679960971.133 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-27T23:49:31.133Z,1679960971.133 [Default:CheckIn:C.Wait] Stopped 2023-03-27T23:49:31.133Z,1679960971.133 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-27T23:49:31.133Z,1679960971.133 [Default:CheckIn:D] Running Loop=1 2023-03-27T23:49:31.581Z,1679960971.581 [Default:CheckIn:D] Stopped 2023-03-27T23:49:31.582Z,1679960971.582 [Default:CheckIn:E] Running Loop=1 2023-03-27T23:49:31.925Z,1679960971.925 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:49:31.985Z,1679960971.985 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 338.305078 min 2023-03-27T23:49:31.985Z,1679960971.985 [Default:CheckIn:E] Stopped 2023-03-27T23:49:31.985Z,1679960971.985 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-27T23:49:31.985Z,1679960971.985 [Default:CheckIn] Stopped 2023-03-27T23:49:31.985Z,1679960971.985 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-27T23:49:31.986Z,1679960971.986 [Default:CheckIn](INFO): Running loop #40 2023-03-27T23:49:31.986Z,1679960971.986 [Default:CheckIn] Running Loop=40 2023-03-27T23:49:31.986Z,1679960971.986 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-27T23:49:31.986Z,1679960971.986 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-27T23:49:33.954Z,1679960973.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234933.00,A,3648.17560,N,12147.28478,W,0.039,0.00,270323,,,D*74 2023-03-27T23:49:33.956Z,1679960973.956 [NAL9602](INFO): GPS fix at 20230327T234933: (36.802927, -121.788080) 2023-03-27T23:49:33.975Z,1679960973.975 [Default:CheckIn:Read_GPS] Stopped 2023-03-27T23:49:33.975Z,1679960973.975 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-27T23:49:41.366Z,1679960981.366 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230327T231936/Courier0015.lzma 2023-03-27T23:49:42.368Z,1679960982.368 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0015.lzma.bak 2023-03-27T23:49:42.368Z,1679960982.368 [DataOverHttps](INFO): SBD MOMSN=17695007 2023-03-27T23:49:44.519Z,1679960984.519 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-27T23:49:47.276Z,1679960987.276 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:49:58.714Z,1679960998.714 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230327T231936/Express0016.lzma 2023-03-27T23:49:59.716Z,1679960999.716 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0016.lzma.bak 2023-03-27T23:49:59.717Z,1679960999.717 [DataOverHttps](INFO): SBD MOMSN=17695010 2023-03-27T23:50:01.037Z,1679961001.037 [Default:CheckIn:Read_Iridium] Stopped 2023-03-27T23:50:01.037Z,1679961001.037 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-27T23:50:01.037Z,1679961001.037 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-27T23:50:02.629Z,1679961002.629 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:50:02.638Z,1679961002.638 [NAL9602](INFO): SBD MO Status=2, MOMSN=39537, MT Status=2, MTMSN=0 2023-03-27T23:50:02.639Z,1679961002.639 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-27T23:50:17.981Z,1679961017.981 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:50:24.449Z,1679961024.449 [NAL9602](INFO): SBD MO Status=2, MOMSN=39537, MT Status=2, MTMSN=0 2023-03-27T23:50:24.450Z,1679961024.450 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-27T23:50:28.943Z,1679961028.943 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:50:32.931Z,1679961032.931 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:50:37.823Z,1679961037.823 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-27T23:50:48.281Z,1679961048.281 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:50:51.922Z,1679961051.922 [NAL9602](INFO): SBD MO Status=2, MOMSN=39537, MT Status=2, MTMSN=0 2023-03-27T23:50:51.922Z,1679961051.922 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-27T23:50:56.015Z,1679961056.015 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:51:03.633Z,1679961063.633 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:51:08.147Z,1679961068.147 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-27T23:51:18.581Z,1679961078.581 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-03-27T23:51:25.452Z,1679961085.452 [NAL9602](INFO): SBD MO Status=2, MOMSN=39537, MT Status=2, MTMSN=0 2023-03-27T23:51:25.453Z,1679961085.453 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-03-27T23:51:33.530Z,1679961093.530 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-03-27T23:51:34.746Z,1679961094.746 [NAL9602](INFO): SBD MO Status=0, MOMSN=39537, MT Status=0, MTMSN=0 2023-03-27T23:51:34.746Z,1679961094.746 [NAL9602](INFO): No messages in MT queue 2023-03-27T23:51:46.464Z,1679961106.464 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5. 2023-03-27T23:51:46.467Z,1679961106.467 [BPC1](INFO): Received data from all battery sticks. 2023-03-27T23:52:05.448Z,1679961125.448 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-27T23:52:05.522Z,1679961125.522 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-27T23:55:01.615Z,1679961301.615 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-27T23:55:01.615Z,1679961301.615 [Default:CheckIn:C.Wait] Stopped 2023-03-27T23:55:01.615Z,1679961301.615 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-27T23:55:01.615Z,1679961301.615 [Default:CheckIn:D] Running Loop=1 2023-03-27T23:55:02.019Z,1679961302.019 [Default:CheckIn:D] Stopped 2023-03-27T23:55:02.019Z,1679961302.019 [Default:CheckIn:E] Running Loop=1 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 343.812370 min 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn:E] Stopped 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn] Stopped 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn](INFO): Running loop #41 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn] Running Loop=41 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-27T23:55:02.420Z,1679961302.420 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-27T23:55:04.434Z,1679961304.434 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235503.00,A,3648.17663,N,12147.28424,W,0.039,0.00,270323,,,D*73 2023-03-27T23:55:04.436Z,1679961304.436 [NAL9602](INFO): GPS fix at 20230327T235503: (36.802944, -121.788071) 2023-03-27T23:55:04.473Z,1679961304.473 [Default:CheckIn:Read_GPS] Stopped 2023-03-27T23:55:04.473Z,1679961304.473 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-27T23:55:12.278Z,1679961312.278 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230327T231936/Courier0018.lzma 2023-03-27T23:55:13.280Z,1679961313.280 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0018.lzma.bak 2023-03-27T23:55:13.280Z,1679961313.280 [DataOverHttps](INFO): SBD MOMSN=17695401 2023-03-27T23:55:14.603Z,1679961314.603 [Reporter](INFO): NAL9602.sigQuality 5 count 2023-03-27T23:55:20.588Z,1679961320.588 [NAL9602](INFO): SBD MO Status=0, MOMSN=39538, MT Status=0, MTMSN=0 2023-03-27T23:55:20.588Z,1679961320.588 [NAL9602](INFO): No messages in MT queue 2023-03-27T23:55:29.118Z,1679961329.118 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230327T231936/Express0019.lzma 2023-03-27T23:55:30.120Z,1679961330.120 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0019.lzma.bak 2023-03-27T23:55:30.120Z,1679961330.120 [DataOverHttps](INFO): SBD MOMSN=17695404 2023-03-27T23:55:31.513Z,1679961331.513 [Default:CheckIn:Read_Iridium] Stopped 2023-03-27T23:55:31.513Z,1679961331.513 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-27T23:55:31.513Z,1679961331.513 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-27T23:55:51.296Z,1679961351.296 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-27T23:55:51.355Z,1679961351.355 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T00:00:32.089Z,1679961632.089 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T00:00:32.090Z,1679961632.090 [Default:CheckIn:C.Wait] Stopped 2023-03-28T00:00:32.090Z,1679961632.090 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T00:00:32.090Z,1679961632.090 [Default:CheckIn:D] Running Loop=1 2023-03-28T00:00:32.505Z,1679961632.505 [Default:CheckIn:D] Stopped 2023-03-28T00:00:32.505Z,1679961632.505 [Default:CheckIn:E] Running Loop=1 2023-03-28T00:00:32.917Z,1679961632.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 349.320475 min 2023-03-28T00:00:32.918Z,1679961632.918 [Default:CheckIn:E] Stopped 2023-03-28T00:00:32.918Z,1679961632.918 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T00:00:32.918Z,1679961632.918 [Default:CheckIn] Stopped 2023-03-28T00:00:32.918Z,1679961632.918 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T00:00:32.918Z,1679961632.918 [Default:CheckIn](INFO): Running loop #42 2023-03-28T00:00:32.918Z,1679961632.918 [Default:CheckIn] Running Loop=42 2023-03-28T00:00:32.918Z,1679961632.918 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T00:00:32.918Z,1679961632.918 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T00:00:34.910Z,1679961634.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000034.00,A,3648.17585,N,12147.28373,W,0.019,0.00,280323,,,D*75 2023-03-28T00:00:34.912Z,1679961634.912 [NAL9602](INFO): GPS fix at 20230328T000034: (36.802931, -121.788062) 2023-03-28T00:00:34.964Z,1679961634.964 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T00:00:34.964Z,1679961634.964 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T00:00:42.734Z,1679961642.734 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230327T231936/Courier0021.lzma 2023-03-28T00:00:43.737Z,1679961643.737 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0021.lzma.bak 2023-03-28T00:00:43.737Z,1679961643.737 [DataOverHttps](INFO): SBD MOMSN=17695414 2023-03-28T00:00:43.864Z,1679961643.864 [Reporter](INFO): NAL9602.sigQuality 2 count 2023-03-28T00:00:52.738Z,1679961652.738 [NAL9602](INFO): SBD MO Status=0, MOMSN=39539, MT Status=0, MTMSN=0 2023-03-28T00:00:52.738Z,1679961652.738 [NAL9602](INFO): No messages in MT queue 2023-03-28T00:01:02.754Z,1679961662.754 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230327T231936/Express0022.lzma 2023-03-28T00:01:03.756Z,1679961663.756 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0022.lzma.bak 2023-03-28T00:01:03.757Z,1679961663.757 [DataOverHttps](INFO): SBD MOMSN=17695418 2023-03-28T00:01:05.267Z,1679961665.267 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T00:01:05.268Z,1679961665.268 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T00:01:05.268Z,1679961665.268 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T00:01:23.422Z,1679961683.422 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T00:01:23.477Z,1679961683.477 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T00:06:05.835Z,1679961965.835 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T00:06:05.835Z,1679961965.835 [Default:CheckIn:C.Wait] Stopped 2023-03-28T00:06:05.835Z,1679961965.835 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T00:06:05.835Z,1679961965.835 [Default:CheckIn:D] Running Loop=1 2023-03-28T00:06:06.239Z,1679961966.239 [Default:CheckIn:D] Stopped 2023-03-28T00:06:06.239Z,1679961966.239 [Default:CheckIn:E] Running Loop=1 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 354.882682 min 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn:E] Stopped 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn] Stopped 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn](INFO): Running loop #43 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn] Running Loop=43 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T00:06:06.655Z,1679961966.655 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T00:06:08.650Z,1679961968.650 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000607.00,A,3648.17713,N,12147.28410,W,0.058,0.00,280323,,,D*79 2023-03-28T00:06:08.652Z,1679961968.652 [NAL9602](INFO): GPS fix at 20230328T000607: (36.802952, -121.788068) 2023-03-28T00:06:08.692Z,1679961968.692 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T00:06:08.692Z,1679961968.692 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T00:06:16.487Z,1679961976.487 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230327T231936/Courier0024.lzma 2023-03-28T00:06:17.489Z,1679961977.489 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0024.lzma.bak 2023-03-28T00:06:17.489Z,1679961977.489 [DataOverHttps](INFO): SBD MOMSN=17695786 2023-03-28T00:06:17.623Z,1679961977.623 [Reporter](INFO): NAL9602.sigQuality 3 count 2023-03-28T00:06:28.097Z,1679961988.097 [NAL9602](INFO): SBD MO Status=0, MOMSN=39540, MT Status=0, MTMSN=0 2023-03-28T00:06:28.097Z,1679961988.097 [NAL9602](INFO): No messages in MT queue 2023-03-28T00:06:36.150Z,1679961996.150 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230327T231936/Express0025.lzma 2023-03-28T00:06:37.153Z,1679961997.153 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0025.lzma.bak 2023-03-28T00:06:37.153Z,1679961997.153 [DataOverHttps](INFO): SBD MOMSN=17695789 2023-03-28T00:06:38.621Z,1679961998.621 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T00:06:38.621Z,1679961998.621 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T00:06:38.621Z,1679961998.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T00:06:58.801Z,1679962018.801 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T00:06:58.824Z,1679962018.824 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T00:07:15.362Z,1679962035.362 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-03-28T00:07:15.362Z,1679962035.362 [DropWeight] Hardware Fault, FailCount= 1 2023-03-28T00:07:15.362Z,1679962035.362 [DropWeight](ERROR): Hardware Fault 2023-03-28T00:07:15.437Z,1679962035.437 [CBIT](INFO): Critical error at 20230328T000715 2023-03-28T00:07:15.439Z,1679962035.439 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-03-28T00:07:15.440Z,1679962035.440 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-03-28T00:07:15.785Z,1679962035.785 [CBIT](INFO): Critical error at 20230328T000715 2023-03-28T00:11:39.209Z,1679962299.209 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T00:11:39.209Z,1679962299.209 [Default:CheckIn:C.Wait] Stopped 2023-03-28T00:11:39.209Z,1679962299.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T00:11:39.209Z,1679962299.209 [Default:CheckIn:D] Running Loop=1 2023-03-28T00:11:39.608Z,1679962299.608 [Default:CheckIn:D] Stopped 2023-03-28T00:11:39.608Z,1679962299.608 [Default:CheckIn:E] Running Loop=1 2023-03-28T00:11:40.018Z,1679962300.018 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 360.438867 min 2023-03-28T00:11:40.018Z,1679962300.018 [Default:CheckIn:E] Stopped 2023-03-28T00:11:40.018Z,1679962300.018 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T00:11:40.023Z,1679962300.023 [Default:CheckIn] Stopped 2023-03-28T00:11:40.023Z,1679962300.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T00:11:40.023Z,1679962300.023 [Default:CheckIn](INFO): Running loop #44 2023-03-28T00:11:40.023Z,1679962300.023 [Default:CheckIn] Running Loop=44 2023-03-28T00:11:40.023Z,1679962300.023 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T00:11:40.023Z,1679962300.023 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T00:11:42.021Z,1679962302.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001141.00,A,3648.17636,N,12147.28546,W,0.000,0.00,280323,,,D*74 2023-03-28T00:11:42.024Z,1679962302.024 [NAL9602](INFO): GPS fix at 20230328T001141: (36.802939, -121.788091) 2023-03-28T00:11:42.035Z,1679962302.035 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T00:11:42.035Z,1679962302.035 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T00:11:49.674Z,1679962309.674 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20230327T231936/Courier0027.lzma 2023-03-28T00:11:49.779Z,1679962309.779 [Reporter](INFO): NAL9602.sigQuality 5 count 2023-03-28T00:11:50.676Z,1679962310.676 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0027.lzma.bak 2023-03-28T00:11:50.676Z,1679962310.676 [DataOverHttps](INFO): SBD MOMSN=17695801 2023-03-28T00:11:55.353Z,1679962315.353 [NAL9602](INFO): SBD MO Status=0, MOMSN=39541, MT Status=0, MTMSN=0 2023-03-28T00:11:55.353Z,1679962315.353 [NAL9602](INFO): No messages in MT queue 2023-03-28T00:12:06.686Z,1679962326.686 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230327T231936/Express0028.lzma 2023-03-28T00:12:07.688Z,1679962327.688 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0028.lzma.bak 2023-03-28T00:12:07.689Z,1679962327.689 [DataOverHttps](INFO): SBD MOMSN=17695807 2023-03-28T00:12:09.116Z,1679962329.116 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T00:12:09.116Z,1679962329.116 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T00:12:09.116Z,1679962329.116 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T00:12:26.072Z,1679962346.072 [NAL9602](INFO): Not Powering down - fast GPS 2023-03-28T00:12:26.100Z,1679962346.100 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-03-28T00:17:09.808Z,1679962629.808 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-28T00:17:09.808Z,1679962629.808 [Default:CheckIn:C.Wait] Stopped 2023-03-28T00:17:09.808Z,1679962629.808 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T00:17:09.809Z,1679962629.809 [Default:CheckIn:D] Running Loop=1 2023-03-28T00:17:10.201Z,1679962630.201 [Default:CheckIn:D] Stopped 2023-03-28T00:17:10.201Z,1679962630.201 [Default:CheckIn:E] Running Loop=1 2023-03-28T00:17:10.603Z,1679962630.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 365.948730 min 2023-03-28T00:17:10.603Z,1679962630.603 [Default:CheckIn:E] Stopped 2023-03-28T00:17:10.603Z,1679962630.603 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-28T00:17:10.604Z,1679962630.604 [Default:CheckIn] Stopped 2023-03-28T00:17:10.604Z,1679962630.604 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T00:17:10.604Z,1679962630.604 [Default:CheckIn](INFO): Running loop #45 2023-03-28T00:17:10.604Z,1679962630.604 [Default:CheckIn] Running Loop=45 2023-03-28T00:17:10.604Z,1679962630.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-28T00:17:10.604Z,1679962630.604 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-28T00:17:12.614Z,1679962632.614 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001711.00,A,3648.16436,N,12147.26682,W,0.622,180.71,280323,,,D*78 2023-03-28T00:17:12.617Z,1679962632.617 [NAL9602](INFO): GPS fix at 20230328T001711: (36.802739, -121.787780) 2023-03-28T00:17:12.629Z,1679962632.629 [Default:CheckIn:Read_GPS] Stopped 2023-03-28T00:17:12.629Z,1679962632.629 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-28T00:17:20.662Z,1679962640.662 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230327T231936/Courier0030.lzma 2023-03-28T00:17:21.664Z,1679962641.664 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Courier0030.lzma.bak 2023-03-28T00:17:21.664Z,1679962641.664 [DataOverHttps](INFO): SBD MOMSN=17696168 2023-03-28T00:17:38.074Z,1679962658.074 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230327T231936/Express0031.lzma 2023-03-28T00:17:39.076Z,1679962659.076 [DataOverHttps](INFO): Moved sent file to Logs/20230327T231936/Express0031.lzma.bak 2023-03-28T00:17:39.077Z,1679962659.077 [DataOverHttps](INFO): SBD MOMSN=17696171 2023-03-28T00:17:40.515Z,1679962660.515 [Default:CheckIn:Read_Iridium] Stopped 2023-03-28T00:17:40.515Z,1679962660.515 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-28T00:17:40.515Z,1679962660.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-28T00:19:11.603Z,1679962751.603 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-03-28T00:19:42.727Z,1679962782.727 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-28T00:20:13.851Z,1679962813.851 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-28T00:20:44.987Z,1679962844.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-28T00:21:16.111Z,1679962876.111 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-28T00:21:18.122Z,1679962878.122 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.161835 2023-03-28T00:21:24.919Z,1679962884.919 [CommandExec](IMPORTANT): got command quit 2023-03-28T00:21:25.923Z,1679962885.923 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:25.923Z,1679962885.923 [CommandExec](INFO): Uninitializing the command executive. 2023-03-28T00:21:25.923Z,1679962885.923 [CommandExec](INFO): Uninitializing the command scheduler. 2023-03-28T00:21:25.923Z,1679962885.923 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:26.119Z,1679962886.119 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-03-28T00:21:26.119Z,1679962886.119 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-03-28T00:21:26.119Z,1679962886.119 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:26.120Z,1679962886.120 [NavChartDb](INFO): Join timeout helper Thread ID is 5184 2023-03-28T00:21:26.215Z,1679962886.215 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:26.215Z,1679962886.215 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:26.219Z,1679962886.219 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-03-28T00:21:26.219Z,1679962886.219 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:26.219Z,1679962886.219 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5185 2023-03-28T00:21:26.475Z,1679962886.475 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:26.475Z,1679962886.475 [WetLabsBB2FL](INFO): Powering down 2023-03-28T00:21:26.476Z,1679962886.476 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:26.487Z,1679962886.487 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-03-28T00:21:26.487Z,1679962886.487 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:26.487Z,1679962886.487 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5186 2023-03-28T00:21:26.931Z,1679962886.931 [CTD_Seabird](INFO): Powering down 2023-03-28T00:21:26.943Z,1679962886.943 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:26.943Z,1679962886.943 [CTD_Seabird](INFO): Powering down 2023-03-28T00:21:26.955Z,1679962886.955 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:26.967Z,1679962886.967 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-03-28T00:21:26.967Z,1679962886.967 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:26.967Z,1679962886.967 [Radio_Surface](INFO): Join timeout helper Thread ID is 5187 2023-03-28T00:21:27.343Z,1679962887.343 [Radio_Surface](INFO): Powering down 2023-03-28T00:21:27.344Z,1679962887.344 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:27.344Z,1679962887.344 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:27.351Z,1679962887.351 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-03-28T00:21:27.351Z,1679962887.351 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:27.351Z,1679962887.351 [Onboard](INFO): Join timeout helper Thread ID is 5189 2023-03-28T00:21:28.187Z,1679962888.187 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-03-28T00:21:30.563Z,1679962890.563 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:30.563Z,1679962890.563 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:30.575Z,1679962890.575 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-03-28T00:21:30.576Z,1679962890.576 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:30.576Z,1679962890.576 [DataOverHttps](INFO): Join timeout helper Thread ID is 5190 2023-03-28T00:21:31.199Z,1679962891.199 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:31.199Z,1679962891.199 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.212Z,1679962891.212 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-03-28T00:21:31.212Z,1679962891.212 [logger ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.212Z,1679962891.212 [logger](INFO): Join timeout helper Thread ID is 5191 2023-03-28T00:21:31.227Z,1679962891.227 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:31.227Z,1679962891.227 [logger ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.239Z,1679962891.239 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-03-28T00:21:31.239Z,1679962891.239 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.240Z,1679962891.240 [CommandLine](INFO): Join timeout helper Thread ID is 5192 2023-03-28T00:21:31.291Z,1679962891.291 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:31.291Z,1679962891.291 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.299Z,1679962891.299 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-03-28T00:21:31.299Z,1679962891.299 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.300Z,1679962891.300 [CommandExec](INFO): Join timeout helper Thread ID is 5193 2023-03-28T00:21:31.301Z,1679962891.301 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-03-28T00:21:31.301Z,1679962891.301 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.301Z,1679962891.301 [controlThread](INFO): Join timeout helper Thread ID is 5194 2023-03-28T00:21:31.583Z,1679962891.583 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-28T00:21:31.583Z,1679962891.583 [controlThread](DEBUG): Uninitializing ControlThread 2023-03-28T00:21:31.583Z,1679962891.583 [AHRS_M2](INFO): Powering down 2023-03-28T00:21:31.655Z,1679962891.655 [MultiRay](INFO): Powering down. 2023-03-28T00:21:31.656Z,1679962891.656 [NAL9602](INFO): Powering down 2023-03-28T00:21:31.659Z,1679962891.659 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-03-28T00:21:31.660Z,1679962891.660 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-03-28T00:21:31.661Z,1679962891.661 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-03-28T00:21:31.661Z,1679962891.661 [MissionManager](INFO): Uninitializing Mission Default 2023-03-28T00:21:31.661Z,1679962891.661 [Default] Stopped 2023-03-28T00:21:31.661Z,1679962891.661 [Default](DEBUG): Aggregate::uninitialize Default 2023-03-28T00:21:31.661Z,1679962891.661 [Default:B.GoToSurface] Stopped 2023-03-28T00:21:31.661Z,1679962891.661 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-03-28T00:21:31.661Z,1679962891.661 [Default:CheckIn] Stopped 2023-03-28T00:21:31.662Z,1679962891.662 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-28T00:21:31.662Z,1679962891.662 [Default:CheckIn:C.Wait] Stopped 2023-03-28T00:21:31.662Z,1679962891.662 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-28T00:21:31.665Z,1679962891.665 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-03-28T00:21:31.665Z,1679962891.665 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-03-28T00:21:31.665Z,1679962891.665 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-03-28T00:21:31.665Z,1679962891.665 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-03-28T00:21:31.666Z,1679962891.666 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-03-28T00:21:31.666Z,1679962891.666 [BuoyancyServo](INFO): Powering down 2023-03-28T00:21:31.679Z,1679962891.679 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-03-28T00:21:31.679Z,1679962891.679 [ElevatorServo](INFO): Powering down 2023-03-28T00:21:31.680Z,1679962891.680 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-03-28T00:21:31.680Z,1679962891.680 [MassServo](INFO): Powering down 2023-03-28T00:21:31.681Z,1679962891.681 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-03-28T00:21:31.681Z,1679962891.681 [RudderServo](INFO): Powering down 2023-03-28T00:21:31.682Z,1679962891.682 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-03-28T00:21:31.682Z,1679962891.682 [ThrusterServo](INFO): Powering down 2023-03-28T00:21:31.683Z,1679962891.683 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-03-28T00:21:31.683Z,1679962891.683 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-03-28T00:21:31.683Z,1679962891.683 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-03-28T00:21:31.683Z,1679962891.683 [CBIT](DEBUG): Powering off loads. 2023-03-28T00:21:31.694Z,1679962891.694 [CBIT](DEBUG): Disabling WDT. 2023-03-28T00:21:31.706Z,1679962891.706 [CBIT](DEBUG): Opening all GF detection circuits. 2023-03-28T00:21:31.707Z,1679962891.707 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.756Z,1679962891.756 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.759Z,1679962891.759 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.766Z,1679962891.766 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.838Z,1679962891.838 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.841Z,1679962891.841 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.881Z,1679962891.881 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-28T00:21:31.950Z,1679962891.950 [logger ThreadHandler](INFO): Thread cancelled.