2020-06-02T17:58:58.603Z,1591120738.603 [CommandLine](IMPORTANT): got command restart logs 2020-06-02T17:59:29.894Z,1591120769.894 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-06-02T17:59:32.700Z,1591120772.700 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:00:15.503Z,1591120815.503 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-06-02T18:00:32.868Z,1591120832.868 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-06-02T18:01:01.226Z,1591120861.226 [CBIT](INFO): Clearing failed state for component DropWeight 2020-06-02T18:01:01.226Z,1591120861.226 [DropWeight] No Fault, FailCount= 1 2020-06-02T18:01:02.768Z,1591120862.768 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-06-02T18:01:02.768Z,1591120862.768 [DropWeight] Hardware Fault, FailCount= 1 2020-06-02T18:01:02.768Z,1591120862.768 [DropWeight](ERROR): Hardware Fault 2020-06-02T18:01:02.811Z,1591120862.811 [CBIT](INFO): Critical error at 20200602T180102 2020-06-02T18:01:02.813Z,1591120862.813 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-06-02T18:01:02.814Z,1591120862.814 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-06-02T18:01:03.264Z,1591120863.264 [CBIT](INFO): Critical error at 20200602T180102 2020-06-02T18:03:16.152Z,1591120996.152 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:03:16.152Z,1591120996.152 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:03:16.152Z,1591120996.152 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:03:16.152Z,1591120996.152 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:03:16.530Z,1591120996.530 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:03:16.530Z,1591120996.530 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:03:16.949Z,1591120996.949 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 128.460465 min 2020-06-02T18:03:16.949Z,1591120996.949 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:03:16.949Z,1591120996.949 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:03:16.950Z,1591120996.950 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:03:16.950Z,1591120996.950 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:03:16.950Z,1591120996.950 [DefaultWithUndock:CheckIn](INFO): Running loop #23 2020-06-02T18:03:16.950Z,1591120996.950 [DefaultWithUndock:CheckIn] Running Loop=23 2020-06-02T18:03:16.950Z,1591120996.950 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:03:16.950Z,1591120996.950 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:03:18.921Z,1591120998.921 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180318.00,A,4131.24981,N,07040.53421,W,25.834,66.54,020620,,,A*73 2020-06-02T18:03:18.923Z,1591120998.923 [NAL9602](INFO): GPS fix at 20200602T180318: (41.520830, -70.675570) 2020-06-02T18:03:18.985Z,1591120998.985 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:03:18.985Z,1591120998.985 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:03:22.602Z,1591121002.602 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200602T174533/Courier0012.lzma 2020-06-02T18:03:23.604Z,1591121003.604 [DataOverHttps](INFO): Moved sent file to Logs/20200602T174533/Courier0012.lzma.bak 2020-06-02T18:03:23.604Z,1591121003.604 [DataOverHttps](INFO): SBD MOMSN=20566 2020-06-02T18:03:32.642Z,1591121012.642 [DataOverHttps](INFO): Sending 365 bytes from file Logs/20200602T175858/Courier0000.lzma 2020-06-02T18:03:33.644Z,1591121013.644 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0000.lzma.bak 2020-06-02T18:03:33.644Z,1591121013.644 [DataOverHttps](INFO): SBD MOMSN=20568 2020-06-02T18:03:42.682Z,1591121022.682 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20200602T174533/Express0013.lzma 2020-06-02T18:03:43.684Z,1591121023.684 [DataOverHttps](INFO): Moved sent file to Logs/20200602T174533/Express0013.lzma.bak 2020-06-02T18:03:43.684Z,1591121023.684 [DataOverHttps](INFO): SBD MOMSN=20574 2020-06-02T18:03:45.181Z,1591121025.181 [NAL9602](INFO): SBD MO Status=0, MOMSN=11271, MT Status=0, MTMSN=0 2020-06-02T18:03:45.181Z,1591121025.181 [NAL9602](INFO): No messages in MT queue 2020-06-02T18:03:52.722Z,1591121032.722 [DataOverHttps](INFO): Sending 350 bytes from file Logs/20200602T175858/Express0001.lzma 2020-06-02T18:03:53.724Z,1591121033.724 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0001.lzma.bak 2020-06-02T18:03:53.724Z,1591121033.724 [DataOverHttps](INFO): SBD MOMSN=20577 2020-06-02T18:04:02.762Z,1591121042.762 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20200602T175858/Express0002.lzma 2020-06-02T18:04:03.764Z,1591121043.764 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0002.lzma.bak 2020-06-02T18:04:03.764Z,1591121043.764 [DataOverHttps](INFO): SBD MOMSN=20581 2020-06-02T18:04:05.009Z,1591121045.009 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:04:05.009Z,1591121045.009 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:04:05.009Z,1591121045.009 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:04:15.906Z,1591121055.906 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T18:07:35.909Z,1591121255.909 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-06-02T18:07:37.132Z,1591121257.132 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-06-02T18:08:36.493Z,1591121316.493 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-06-02T18:08:55.913Z,1591121335.913 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T18:09:01.972Z,1591121341.972 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-06-02T18:09:05.655Z,1591121345.655 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:09:05.655Z,1591121345.655 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:09:05.655Z,1591121345.655 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:09:05.655Z,1591121345.655 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:09:05.000Z,1591121346.000 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:09:06.000Z,1591121346.000 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:09:06.411Z,1591121346.411 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 134.284969 min 2020-06-02T18:09:06.411Z,1591121346.411 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:09:06.411Z,1591121346.411 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:09:06.411Z,1591121346.411 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:09:06.411Z,1591121346.411 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:09:06.412Z,1591121346.412 [DefaultWithUndock:CheckIn](INFO): Running loop #24 2020-06-02T18:09:06.412Z,1591121346.412 [DefaultWithUndock:CheckIn] Running Loop=24 2020-06-02T18:09:06.412Z,1591121346.412 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:09:06.412Z,1591121346.412 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:09:08.384Z,1591121348.384 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180907.00,A,4131.46403,N,07040.31404,W,0.700,68.71,020620,,,A*41 2020-06-02T18:09:08.387Z,1591121348.387 [NAL9602](INFO): GPS fix at 20200602T180907: (41.524400, -70.671901) 2020-06-02T18:09:08.460Z,1591121348.460 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:09:08.460Z,1591121348.460 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:09:12.050Z,1591121352.050 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T175858/Courier0005.lzma 2020-06-02T18:09:13.055Z,1591121353.055 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0005.lzma.bak 2020-06-02T18:09:13.056Z,1591121353.056 [DataOverHttps](INFO): SBD MOMSN=20584 2020-06-02T18:09:22.090Z,1591121362.090 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200602T175858/Express0007.lzma 2020-06-02T18:09:23.092Z,1591121363.092 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0007.lzma.bak 2020-06-02T18:09:23.093Z,1591121363.093 [DataOverHttps](INFO): SBD MOMSN=20587 2020-06-02T18:09:24.249Z,1591121364.249 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:09:24.249Z,1591121364.249 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:09:24.249Z,1591121364.249 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:10:17.502Z,1591121417.502 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:10:53.075Z,1591121453.075 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T18:11:01.508Z,1591121461.508 [NAL9602](INFO): SBD MO Status=0, MOMSN=11272, MT Status=0, MTMSN=0 2020-06-02T18:11:01.508Z,1591121461.508 [NAL9602](INFO): No messages in MT queue 2020-06-02T18:11:32.224Z,1591121492.224 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T18:12:36.932Z,1591121556.932 [RDI_PathfinderUp](ERROR): only read -1 of 1 data item for altitude 2020-06-02T18:13:36.679Z,1591121616.679 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-06-02T18:13:36.681Z,1591121616.681 [BPC1](INFO): Received data from all battery sticks. 2020-06-02T18:14:24.744Z,1591121664.744 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:14:24.744Z,1591121664.744 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:14:24.744Z,1591121664.744 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:14:24.744Z,1591121664.744 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:14:25.184Z,1591121665.184 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:14:25.184Z,1591121665.184 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:14:25.572Z,1591121665.572 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 139.604704 min 2020-06-02T18:14:25.572Z,1591121665.572 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:14:25.572Z,1591121665.572 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:14:25.572Z,1591121665.572 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:14:25.572Z,1591121665.572 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:14:25.572Z,1591121665.572 [DefaultWithUndock:CheckIn](INFO): Running loop #25 2020-06-02T18:14:25.572Z,1591121665.572 [DefaultWithUndock:CheckIn] Running Loop=25 2020-06-02T18:14:25.572Z,1591121665.572 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:14:25.573Z,1591121665.573 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:14:27.569Z,1591121667.569 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181426.00,A,4131.46280,N,07040.30939,W,0.039,125.62,020620,,,D*73 2020-06-02T18:14:27.571Z,1591121667.571 [NAL9602](INFO): GPS fix at 20200602T181426: (41.524380, -70.671823) 2020-06-02T18:14:27.595Z,1591121667.595 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:14:27.596Z,1591121667.596 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:14:31.370Z,1591121671.370 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20200602T175858/Courier0009.lzma 2020-06-02T18:14:32.372Z,1591121672.372 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0009.lzma.bak 2020-06-02T18:14:32.372Z,1591121672.372 [DataOverHttps](INFO): SBD MOMSN=20591 2020-06-02T18:14:41.410Z,1591121681.410 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200602T175858/Express0010.lzma 2020-06-02T18:14:42.412Z,1591121682.412 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0010.lzma.bak 2020-06-02T18:14:42.412Z,1591121682.412 [DataOverHttps](INFO): SBD MOMSN=20595 2020-06-02T18:14:43.750Z,1591121683.750 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:14:43.750Z,1591121683.750 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:14:43.750Z,1591121683.750 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:14:55.423Z,1591121695.423 [NAL9602](INFO): SBD MO Status=0, MOMSN=11273, MT Status=0, MTMSN=0 2020-06-02T18:14:55.423Z,1591121695.423 [NAL9602](INFO): No messages in MT queue 2020-06-02T18:15:03.149Z,1591121703.149 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-06-02T18:15:17.271Z,1591121717.271 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:15:18.904Z,1591121718.904 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-06-02T18:15:26.131Z,1591121726.131 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T18:15:55.338Z,1591121755.338 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout 2020-06-02T18:15:55.338Z,1591121755.338 [RDI_PathfinderUp] Communications Fault, FailCount= 1 2020-06-02T18:15:55.338Z,1591121755.338 [RDI_PathfinderUp](ERROR): Communications Fault 2020-06-02T18:15:55.378Z,1591121755.378 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp 2020-06-02T18:15:55.706Z,1591121755.706 [RDI_PathfinderUp](INFO): Powering down 2020-06-02T18:15:56.492Z,1591121756.492 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp 2020-06-02T18:15:56.492Z,1591121756.492 [RDI_PathfinderUp] No Fault, FailCount= 1 2020-06-02T18:16:02.925Z,1591121762.925 [CBIT](INFO): Clearing failed state for component DropWeight 2020-06-02T18:16:02.925Z,1591121762.925 [DropWeight] No Fault, FailCount= 1 2020-06-02T18:16:04.504Z,1591121764.504 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-06-02T18:16:04.504Z,1591121764.504 [DropWeight] Hardware Fault, FailCount= 1 2020-06-02T18:16:04.504Z,1591121764.504 [DropWeight](ERROR): Hardware Fault 2020-06-02T18:16:04.541Z,1591121764.541 [CBIT](INFO): Critical error at 20200602T181604 2020-06-02T18:16:04.543Z,1591121764.543 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-06-02T18:16:04.544Z,1591121764.544 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-06-02T18:16:04.951Z,1591121764.951 [CBIT](INFO): Critical error at 20200602T181604 2020-06-02T18:18:01.748Z,1591121881.748 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:18:43.740Z,1591121923.740 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T18:19:44.336Z,1591121984.336 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:19:44.336Z,1591121984.336 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:19:44.336Z,1591121984.336 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:19:44.336Z,1591121984.336 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:19:44.752Z,1591121984.752 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:19:44.753Z,1591121984.753 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:19:45.180Z,1591121985.180 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 144.930827 min 2020-06-02T18:19:45.180Z,1591121985.180 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:19:45.180Z,1591121985.180 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:19:45.181Z,1591121985.181 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:19:45.181Z,1591121985.181 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:19:45.181Z,1591121985.181 [DefaultWithUndock:CheckIn](INFO): Running loop #26 2020-06-02T18:19:45.181Z,1591121985.181 [DefaultWithUndock:CheckIn] Running Loop=26 2020-06-02T18:19:45.181Z,1591121985.181 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:19:45.181Z,1591121985.181 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:19:47.125Z,1591121987.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181946.00,A,4131.46438,N,07040.30898,W,1.633,70.70,020620,,,D*48 2020-06-02T18:19:47.138Z,1591121987.138 [NAL9602](INFO): GPS fix at 20200602T181946: (41.524406, -70.671816) 2020-06-02T18:19:47.203Z,1591121987.203 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:19:47.203Z,1591121987.203 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:19:50.662Z,1591121990.662 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200602T175858/Courier0012.lzma 2020-06-02T18:19:51.664Z,1591121991.664 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0012.lzma.bak 2020-06-02T18:19:51.665Z,1591121991.665 [DataOverHttps](INFO): SBD MOMSN=20599 2020-06-02T18:20:00.708Z,1591122000.708 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200602T175858/Express0013.lzma 2020-06-02T18:20:01.707Z,1591122001.707 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0013.lzma.bak 2020-06-02T18:20:01.708Z,1591122001.708 [DataOverHttps](INFO): SBD MOMSN=20604 2020-06-02T18:20:02.905Z,1591122002.905 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:20:02.906Z,1591122002.906 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:20:02.906Z,1591122002.906 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:20:12.560Z,1591122012.560 [NAL9602](INFO): SBD MO Status=2, MOMSN=11274, MT Status=2, MTMSN=0 2020-06-02T18:20:12.560Z,1591122012.560 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-06-02T18:20:40.442Z,1591122040.442 [NAL9602](INFO): SBD MO Status=2, MOMSN=11274, MT Status=2, MTMSN=0 2020-06-02T18:20:40.442Z,1591122040.442 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-06-02T18:21:05.132Z,1591122065.132 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-06-02T18:22:43.742Z,1591122163.742 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-06-02T18:24:06.481Z,1591122246.481 [NAL9602](INFO): SBD MO Status=2, MOMSN=11274, MT Status=2, MTMSN=0 2020-06-02T18:24:06.482Z,1591122246.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-06-02T18:24:09.762Z,1591122249.762 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-06-02T18:24:28.314Z,1591122268.314 [NAL9602](INFO): SBD MO Status=2, MOMSN=11274, MT Status=2, MTMSN=0 2020-06-02T18:24:28.314Z,1591122268.314 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-06-02T18:24:49.308Z,1591122289.308 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-06-02T18:25:03.491Z,1591122303.491 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:25:03.492Z,1591122303.492 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:25:03.492Z,1591122303.492 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:25:03.492Z,1591122303.492 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:25:03.887Z,1591122303.887 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:25:03.887Z,1591122303.887 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:25:04.300Z,1591122304.300 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 150.249740 min 2020-06-02T18:25:04.300Z,1591122304.300 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:25:04.300Z,1591122304.300 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:25:04.300Z,1591122304.300 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:25:04.301Z,1591122304.301 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:25:04.301Z,1591122304.301 [DefaultWithUndock:CheckIn](INFO): Running loop #27 2020-06-02T18:25:04.301Z,1591122304.301 [DefaultWithUndock:CheckIn] Running Loop=27 2020-06-02T18:25:04.301Z,1591122304.301 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:25:04.301Z,1591122304.301 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:25:05.123Z,1591122305.123 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:25:06.273Z,1591122306.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182505.00,A,4131.47280,N,07040.27955,W,0.311,166.83,020620,,,D*7C 2020-06-02T18:25:06.275Z,1591122306.275 [NAL9602](INFO): GPS fix at 20200602T182505: (41.524547, -70.671326) 2020-06-02T18:25:06.328Z,1591122306.328 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:25:06.328Z,1591122306.328 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:25:09.982Z,1591122309.982 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T175858/Courier0015.lzma 2020-06-02T18:25:10.984Z,1591122310.984 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0015.lzma.bak 2020-06-02T18:25:10.985Z,1591122310.985 [DataOverHttps](INFO): SBD MOMSN=20607 2020-06-02T18:25:20.022Z,1591122320.022 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20200602T175858/Express0016.lzma 2020-06-02T18:25:21.024Z,1591122321.024 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0016.lzma.bak 2020-06-02T18:25:21.024Z,1591122321.024 [DataOverHttps](INFO): SBD MOMSN=20610 2020-06-02T18:25:22.089Z,1591122322.089 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:25:22.089Z,1591122322.089 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:25:22.089Z,1591122322.089 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:25:38.588Z,1591122338.588 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T18:26:20.667Z,1591122380.667 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:29:30.151Z,1591122570.151 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T18:30:22.684Z,1591122622.684 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:30:22.684Z,1591122622.684 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:30:22.684Z,1591122622.684 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:30:22.684Z,1591122622.684 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:30:23.080Z,1591122623.080 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:30:23.080Z,1591122623.080 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:30:23.487Z,1591122623.487 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 155.569629 min 2020-06-02T18:30:23.487Z,1591122623.487 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:30:23.487Z,1591122623.487 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:30:23.487Z,1591122623.487 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:30:23.488Z,1591122623.488 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:30:23.488Z,1591122623.488 [DefaultWithUndock:CheckIn](INFO): Running loop #28 2020-06-02T18:30:23.488Z,1591122623.488 [DefaultWithUndock:CheckIn] Running Loop=28 2020-06-02T18:30:23.488Z,1591122623.488 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:30:23.488Z,1591122623.488 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:30:25.120Z,1591122625.120 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-06-02T18:30:25.466Z,1591122625.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183024.00,A,4131.46008,N,07040.27372,W,0.564,159.06,020620,,,A*77 2020-06-02T18:30:25.468Z,1591122625.468 [NAL9602](INFO): GPS fix at 20200602T183024: (41.524335, -70.671229) 2020-06-02T18:30:25.512Z,1591122625.512 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:30:25.512Z,1591122625.512 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:30:29.294Z,1591122629.294 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200602T175858/Courier0018.lzma 2020-06-02T18:30:30.296Z,1591122630.296 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0018.lzma.bak 2020-06-02T18:30:30.296Z,1591122630.296 [DataOverHttps](INFO): SBD MOMSN=20614 2020-06-02T18:30:39.334Z,1591122639.334 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200602T175858/Express0019.lzma 2020-06-02T18:30:40.336Z,1591122640.336 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0019.lzma.bak 2020-06-02T18:30:40.336Z,1591122640.336 [DataOverHttps](INFO): SBD MOMSN=20617 2020-06-02T18:30:41.663Z,1591122641.663 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:30:41.663Z,1591122641.663 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:30:41.663Z,1591122641.663 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:30:49.755Z,1591122649.755 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:31:04.716Z,1591122664.716 [CBIT](INFO): Clearing failed state for component DropWeight 2020-06-02T18:31:04.716Z,1591122664.716 [DropWeight] No Fault, FailCount= 1 2020-06-02T18:31:06.267Z,1591122666.267 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-06-02T18:31:06.267Z,1591122666.267 [DropWeight] Hardware Fault, FailCount= 1 2020-06-02T18:31:06.267Z,1591122666.267 [DropWeight](ERROR): Hardware Fault 2020-06-02T18:31:06.345Z,1591122666.345 [CBIT](INFO): Critical error at 20200602T183106 2020-06-02T18:31:06.352Z,1591122666.352 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-06-02T18:31:06.352Z,1591122666.352 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-06-02T18:31:06.704Z,1591122666.704 [CBIT](INFO): Critical error at 20200602T183106 2020-06-02T18:32:48.138Z,1591122768.138 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-06-02T18:32:58.172Z,1591122778.172 [NAL9602](INFO): SBD MO Status=0, MOMSN=11274, MT Status=0, MTMSN=0 2020-06-02T18:32:58.172Z,1591122778.172 [NAL9602](INFO): No messages in MT queue 2020-06-02T18:33:28.876Z,1591122808.876 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T18:35:28.491Z,1591122928.491 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:35:42.226Z,1591122942.226 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:35:42.226Z,1591122942.226 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:35:42.226Z,1591122942.226 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:35:42.226Z,1591122942.226 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:35:42.643Z,1591122942.643 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:35:42.643Z,1591122942.643 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 160.895687 min 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn](INFO): Running loop #29 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn] Running Loop=29 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:35:43.051Z,1591122943.051 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:35:45.030Z,1591122945.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183544.00,A,4131.46981,N,07040.28046,W,0.564,179.76,020620,,,A*72 2020-06-02T18:35:45.032Z,1591122945.032 [NAL9602](INFO): GPS fix at 20200602T183544: (41.524497, -70.671341) 2020-06-02T18:35:45.115Z,1591122945.115 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:35:45.115Z,1591122945.115 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:35:48.586Z,1591122948.586 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20200602T175858/Courier0021.lzma 2020-06-02T18:35:49.588Z,1591122949.588 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0021.lzma.bak 2020-06-02T18:35:49.588Z,1591122949.588 [DataOverHttps](INFO): SBD MOMSN=20621 2020-06-02T18:35:58.630Z,1591122958.630 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200602T175858/Express0022.lzma 2020-06-02T18:35:59.632Z,1591122959.632 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0022.lzma.bak 2020-06-02T18:35:59.632Z,1591122959.632 [DataOverHttps](INFO): SBD MOMSN=20626 2020-06-02T18:36:00.830Z,1591122960.830 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:36:00.830Z,1591122960.830 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:36:00.830Z,1591122960.830 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:36:27.114Z,1591122987.114 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-06-02T18:37:10.742Z,1591123030.742 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:37:22.846Z,1591123042.846 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:38:48.481Z,1591123128.481 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:39:29.282Z,1591123169.282 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:40:08.122Z,1591123208.122 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-06-02T18:40:08.477Z,1591123208.477 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:40:47.231Z,1591123247.231 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-06-02T18:41:01.408Z,1591123261.408 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:41:01.408Z,1591123261.408 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:41:01.408Z,1591123261.408 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:41:01.408Z,1591123261.408 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:41:01.833Z,1591123261.833 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:41:01.833Z,1591123261.833 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:41:02.215Z,1591123262.215 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 166.215527 min 2020-06-02T18:41:02.216Z,1591123262.216 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:41:02.216Z,1591123262.216 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:41:02.216Z,1591123262.216 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:41:02.216Z,1591123262.216 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:41:02.216Z,1591123262.216 [DefaultWithUndock:CheckIn](INFO): Running loop #30 2020-06-02T18:41:02.216Z,1591123262.216 [DefaultWithUndock:CheckIn] Running Loop=30 2020-06-02T18:41:02.216Z,1591123262.216 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:41:02.216Z,1591123262.216 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:41:04.206Z,1591123264.206 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184103.00,A,4131.47426,N,07040.28174,W,0.447,179.76,020620,,,A*73 2020-06-02T18:41:04.209Z,1591123264.209 [NAL9602](INFO): GPS fix at 20200602T184103: (41.524571, -70.671362) 2020-06-02T18:41:04.265Z,1591123264.265 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:41:04.265Z,1591123264.265 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:41:07.898Z,1591123267.898 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T175858/Courier0024.lzma 2020-06-02T18:41:08.900Z,1591123268.900 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0024.lzma.bak 2020-06-02T18:41:08.900Z,1591123268.900 [DataOverHttps](INFO): SBD MOMSN=20629 2020-06-02T18:41:09.102Z,1591123269.102 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items 2020-06-02T18:41:17.938Z,1591123277.938 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20200602T175858/Express0025.lzma 2020-06-02T18:41:18.940Z,1591123278.940 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0025.lzma.bak 2020-06-02T18:41:18.940Z,1591123278.940 [DataOverHttps](INFO): SBD MOMSN=20632 2020-06-02T18:41:20.025Z,1591123280.025 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:41:20.026Z,1591123280.026 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:41:20.026Z,1591123280.026 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:41:31.740Z,1591123291.740 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:41:36.528Z,1591123296.528 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T18:42:29.092Z,1591123349.092 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T18:42:30.310Z,1591123350.310 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-06-02T18:43:28.465Z,1591123408.465 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:43:50.303Z,1591123430.303 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-06-02T18:46:06.443Z,1591123566.443 [CBIT](INFO): Clearing failed state for component DropWeight 2020-06-02T18:46:06.443Z,1591123566.443 [DropWeight] No Fault, FailCount= 1 2020-06-02T18:46:08.007Z,1591123568.007 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-06-02T18:46:08.007Z,1591123568.007 [DropWeight] Hardware Fault, FailCount= 1 2020-06-02T18:46:08.007Z,1591123568.007 [DropWeight](ERROR): Hardware Fault 2020-06-02T18:46:08.124Z,1591123568.124 [CBIT](INFO): Critical error at 20200602T184608 2020-06-02T18:46:08.131Z,1591123568.131 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-06-02T18:46:08.131Z,1591123568.131 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-06-02T18:46:08.477Z,1591123568.477 [CBIT](INFO): Critical error at 20200602T184608 2020-06-02T18:46:20.581Z,1591123580.581 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:46:20.581Z,1591123580.581 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:46:20.581Z,1591123580.581 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:46:20.581Z,1591123580.581 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:46:20.978Z,1591123580.978 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:46:20.978Z,1591123580.978 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 171.534587 min 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn](INFO): Running loop #31 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn] Running Loop=31 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:46:21.412Z,1591123581.412 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:46:23.362Z,1591123583.362 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184622.00,A,4131.47203,N,07040.28324,W,0.739,179.76,020620,,,A*7B 2020-06-02T18:46:23.364Z,1591123583.364 [NAL9602](INFO): GPS fix at 20200602T184622: (41.524534, -70.671387) 2020-06-02T18:46:23.400Z,1591123583.400 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:46:23.400Z,1591123583.400 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:46:27.190Z,1591123587.190 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20200602T175858/Courier0027.lzma 2020-06-02T18:46:28.196Z,1591123588.196 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0027.lzma.bak 2020-06-02T18:46:28.196Z,1591123588.196 [DataOverHttps](INFO): SBD MOMSN=20636 2020-06-02T18:46:30.660Z,1591123590.660 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-06-02T18:46:37.234Z,1591123597.234 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200602T175858/Express0028.lzma 2020-06-02T18:46:38.236Z,1591123598.236 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0028.lzma.bak 2020-06-02T18:46:38.236Z,1591123598.236 [DataOverHttps](INFO): SBD MOMSN=20641 2020-06-02T18:46:39.548Z,1591123599.548 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:46:39.549Z,1591123599.549 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:46:39.549Z,1591123599.549 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:46:54.072Z,1591123614.072 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-06-02T18:46:54.146Z,1591123614.146 [NAL9602](FAULT): received: +CSQ:0 OK274, 0, 0, 0, 0 OK 2020-06-02T18:46:54.147Z,1591123614.147 [NAL9602] Data Fault, FailCount= 1 2020-06-02T18:46:54.147Z,1591123614.147 [NAL9602](ERROR): Data Fault 2020-06-02T18:46:54.224Z,1591123614.224 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-02T18:46:54.474Z,1591123614.474 [NAL9602](INFO): Powering down 2020-06-02T18:46:54.872Z,1591123614.872 [NAL9602](FAULT): LCB fault: Software Overcurrent. 2020-06-02T18:46:54.872Z,1591123614.872 [NAL9602] Hardware Fault, FailCount= 1 2020-06-02T18:46:54.872Z,1591123614.872 [NAL9602](ERROR): Hardware Fault 2020-06-02T18:46:55.336Z,1591123615.336 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-02T18:46:55.336Z,1591123615.336 [NAL9602] No Fault, FailCount= 1 2020-06-02T18:47:24.777Z,1591123644.777 [NAL9602](INFO): Powering up NAL9602 2020-06-02T18:47:34.492Z,1591123654.492 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-06-02T18:47:35.681Z,1591123655.681 [NAL9602](INFO): NAL9602 initialized 2020-06-02T18:48:06.788Z,1591123686.788 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T18:50:14.475Z,1591123814.475 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:50:54.476Z,1591123854.476 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:51:40.142Z,1591123900.142 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:51:40.142Z,1591123900.142 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:51:40.142Z,1591123900.142 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:51:40.142Z,1591123900.142 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:51:40.553Z,1591123900.553 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:51:40.553Z,1591123900.553 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 176.860856 min 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn](INFO): Running loop #32 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn] Running Loop=32 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:51:40.964Z,1591123900.964 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:51:44.948Z,1591123904.948 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-06-02T18:52:35.475Z,1591123955.475 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-06-02T18:53:06.962Z,1591123986.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185306.00,A,4131.46959,N,07040.28354,W,0.311,0.00,020620,,,A*7B 2020-06-02T18:53:06.964Z,1591123986.964 [NAL9602](INFO): GPS fix at 20200602T185306: (41.524493, -70.671392) 2020-06-02T18:53:07.018Z,1591123987.018 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:53:07.018Z,1591123987.018 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:53:08.862Z,1591123988.862 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T175858/Courier0030.lzma 2020-06-02T18:53:09.864Z,1591123989.864 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0030.lzma.bak 2020-06-02T18:53:09.864Z,1591123989.864 [DataOverHttps](INFO): SBD MOMSN=20644 2020-06-02T18:53:18.902Z,1591123998.902 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20200602T175858/Express0031.lzma 2020-06-02T18:53:19.907Z,1591123999.907 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0031.lzma.bak 2020-06-02T18:53:19.907Z,1591123999.907 [DataOverHttps](INFO): SBD MOMSN=20647 2020-06-02T18:53:21.139Z,1591124001.139 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:53:21.139Z,1591124001.139 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:53:21.139Z,1591124001.139 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:53:39.275Z,1591124019.275 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T18:54:31.828Z,1591124071.828 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-06-02T18:54:41.116Z,1591124081.116 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:54:58.149Z,1591124098.149 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-06-02T18:55:20.372Z,1591124120.372 [CommandLine](IMPORTANT): got command burn off 2020-06-02T18:55:20.372Z,1591124120.372 [CommandLine](IMPORTANT): Deactivating dropweight wire 2020-06-02T18:56:02.757Z,1591124162.757 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items 2020-06-02T18:56:18.116Z,1591124178.116 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:56:58.474Z,1591124218.474 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:57:21.104Z,1591124241.104 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:58:18.465Z,1591124298.465 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-06-02T18:58:21.742Z,1591124301.742 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:58:21.751Z,1591124301.751 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T18:58:21.751Z,1591124301.751 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T18:58:21.751Z,1591124301.751 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T18:58:21.751Z,1591124301.751 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T18:58:22.106Z,1591124302.106 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T18:58:22.106Z,1591124302.106 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 183.553402 min 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn](INFO): Running loop #33 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn] Running Loop=33 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T18:58:22.525Z,1591124302.525 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T18:58:24.506Z,1591124304.506 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185823.00,A,4131.46257,N,07040.28170,W,0.525,319.46,020620,,,A*7E 2020-06-02T18:58:24.508Z,1591124304.508 [NAL9602](INFO): GPS fix at 20200602T185823: (41.524376, -70.671362) 2020-06-02T18:58:24.561Z,1591124304.561 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T18:58:24.561Z,1591124304.561 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T18:58:28.154Z,1591124308.154 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T175858/Courier0033.lzma 2020-06-02T18:58:29.159Z,1591124309.159 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0033.lzma.bak 2020-06-02T18:58:29.160Z,1591124309.160 [DataOverHttps](INFO): SBD MOMSN=20654 2020-06-02T18:58:38.194Z,1591124318.194 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20200602T175858/Express0034.lzma 2020-06-02T18:58:39.197Z,1591124319.197 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0034.lzma.bak 2020-06-02T18:58:39.197Z,1591124319.197 [DataOverHttps](INFO): SBD MOMSN=20657 2020-06-02T18:58:40.318Z,1591124320.318 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T18:58:40.318Z,1591124320.318 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T18:58:40.318Z,1591124320.318 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T18:58:58.117Z,1591124338.117 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T18:58:58.467Z,1591124338.467 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T18:59:17.446Z,1591124357.446 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-06-02T19:00:08.615Z,1591124408.615 [CommandLine](IMPORTANT): got command burn on 2020-06-02T19:00:08.615Z,1591124408.615 [CommandLine](IMPORTANT): Activating dropweight wire 2020-06-02T19:00:24.147Z,1591124424.147 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T19:00:24.490Z,1591124424.490 [NAL9602](INFO): SBD MO Status=2, MOMSN=11275, MT Status=2, MTMSN=0 2020-06-02T19:00:24.490Z,1591124424.490 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-06-02T19:00:27.017Z,1591124427.017 [CommandLine](IMPORTANT): got command burn off 2020-06-02T19:00:27.020Z,1591124427.020 [CommandLine](IMPORTANT): Deactivating dropweight wire 2020-06-02T19:01:08.220Z,1591124468.220 [CBIT](INFO): Clearing failed state for component DropWeight 2020-06-02T19:01:08.220Z,1591124468.220 [DropWeight] No Fault, FailCount= 1 2020-06-02T19:02:58.463Z,1591124578.463 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T19:03:26.290Z,1591124606.290 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-06-02T19:03:40.872Z,1591124620.872 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T19:03:40.872Z,1591124620.872 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T19:03:40.873Z,1591124620.873 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T19:03:40.873Z,1591124620.873 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T19:03:41.271Z,1591124621.271 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T19:03:41.272Z,1591124621.272 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T19:03:41.709Z,1591124621.709 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 188.872819 min 2020-06-02T19:03:41.710Z,1591124621.710 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T19:03:41.710Z,1591124621.710 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T19:03:41.710Z,1591124621.710 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T19:03:41.711Z,1591124621.711 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T19:03:41.711Z,1591124621.711 [DefaultWithUndock:CheckIn](INFO): Running loop #34 2020-06-02T19:03:41.711Z,1591124621.711 [DefaultWithUndock:CheckIn] Running Loop=34 2020-06-02T19:03:41.712Z,1591124621.712 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T19:03:41.712Z,1591124621.712 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T19:03:43.669Z,1591124623.669 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190343.00,A,4131.46171,N,07040.28347,W,1.050,319.46,020620,,,A*70 2020-06-02T19:03:43.679Z,1591124623.679 [NAL9602](INFO): GPS fix at 20200602T190343: (41.524362, -70.671391) 2020-06-02T19:03:43.738Z,1591124623.738 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T19:03:43.738Z,1591124623.738 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T19:03:48.438Z,1591124628.438 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T175858/Courier0036.lzma 2020-06-02T19:03:49.442Z,1591124629.442 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0036.lzma.bak 2020-06-02T19:03:49.442Z,1591124629.442 [DataOverHttps](INFO): SBD MOMSN=20662 2020-06-02T19:03:58.478Z,1591124638.478 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20200602T175858/Express0037.lzma 2020-06-02T19:03:59.480Z,1591124639.480 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0037.lzma.bak 2020-06-02T19:03:59.480Z,1591124639.480 [DataOverHttps](INFO): SBD MOMSN=20665 2020-06-02T19:04:00.669Z,1591124640.669 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T19:04:00.669Z,1591124640.669 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T19:04:00.670Z,1591124640.670 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T19:04:16.384Z,1591124656.384 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T19:09:01.258Z,1591124941.258 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-02T19:09:01.258Z,1591124941.258 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T19:09:01.258Z,1591124941.258 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T19:09:01.258Z,1591124941.258 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-06-02T19:09:01.676Z,1591124941.676 [DefaultWithUndock:CheckIn:D] Stopped 2020-06-02T19:09:01.676Z,1591124941.676 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 194.212891 min 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn:E] Stopped 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn](INFO): Running loop #35 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn] Running Loop=35 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-06-02T19:09:02.055Z,1591124942.055 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-06-02T19:09:04.034Z,1591124944.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190903.00,A,4131.47211,N,07040.28066,W,0.408,0.00,020620,,,A*7B 2020-06-02T19:09:04.047Z,1591124944.047 [NAL9602](INFO): GPS fix at 20200602T190903: (41.524535, -70.671344) 2020-06-02T19:09:04.131Z,1591124944.131 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-06-02T19:09:04.131Z,1591124944.131 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-06-02T19:09:06.102Z,1591124946.102 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-06-02T19:09:07.750Z,1591124947.750 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T175858/Courier0039.lzma 2020-06-02T19:09:08.752Z,1591124948.752 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Courier0039.lzma.bak 2020-06-02T19:09:08.752Z,1591124948.752 [DataOverHttps](INFO): SBD MOMSN=20673 2020-06-02T19:09:17.792Z,1591124957.792 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200602T175858/Express0040.lzma 2020-06-02T19:09:18.792Z,1591124958.792 [DataOverHttps](INFO): Moved sent file to Logs/20200602T175858/Express0040.lzma.bak 2020-06-02T19:09:18.792Z,1591124958.792 [DataOverHttps](INFO): SBD MOMSN=20676 2020-06-02T19:09:19.877Z,1591124959.877 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-06-02T19:09:19.877Z,1591124959.877 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-06-02T19:09:19.877Z,1591124959.877 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-02T19:09:34.758Z,1591124974.758 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-06-02T19:09:34.838Z,1591124974.838 [NAL9602](FAULT): received: +CSQ:0 OK275, 2, 0, 0, 0 OK 2020-06-02T19:09:34.838Z,1591124974.838 [NAL9602] Data Fault, FailCount= 1 2020-06-02T19:09:34.838Z,1591124974.838 [NAL9602](ERROR): Data Fault 2020-06-02T19:09:34.953Z,1591124974.953 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-02T19:09:35.148Z,1591124975.148 [NAL9602](INFO): Powering down 2020-06-02T19:09:35.552Z,1591124975.552 [NAL9602](FAULT): LCB fault: Software Overcurrent. 2020-06-02T19:09:35.552Z,1591124975.552 [NAL9602] Hardware Fault, FailCount= 1 2020-06-02T19:09:35.553Z,1591124975.553 [NAL9602](ERROR): Hardware Fault 2020-06-02T19:09:36.021Z,1591124976.021 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-02T19:09:36.022Z,1591124976.022 [NAL9602] No Fault, FailCount= 1 2020-06-02T19:10:05.448Z,1591125005.448 [NAL9602](INFO): Powering up NAL9602 2020-06-02T19:10:16.356Z,1591125016.356 [NAL9602](INFO): NAL9602 initialized 2020-06-02T19:10:30.120Z,1591125030.120 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-06-02T19:10:47.469Z,1591125047.469 [NAL9602](INFO): Not Powering down - fast GPS 2020-06-02T19:12:28.123Z,1591125148.123 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-06-02T19:12:54.691Z,1591125174.691 [DataOverHttps](IMPORTANT): SBD MTMSN=20200602T191253 2020-06-02T19:12:58.706Z,1591125178.706 [DataOverHttps](INFO): Received command:restart app 2020-06-02T19:12:58.755Z,1591125178.755 [CommandLine](IMPORTANT): got command restart application 2020-06-02T19:12:59.758Z,1591125179.758 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-02T19:12:59.759Z,1591125179.759 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:12:59.838Z,1591125179.838 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-06-02T19:12:59.838Z,1591125179.838 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:12:59.839Z,1591125179.839 [CommandLine](INFO): Join timeout helper Thread ID is 9613 2020-06-02T19:12:59.840Z,1591125179.840 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-06-02T19:12:59.840Z,1591125179.840 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:12:59.840Z,1591125179.840 [NavChartDb](INFO): Join timeout helper Thread ID is 9614 2020-06-02T19:13:00.150Z,1591125180.150 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-02T19:13:00.151Z,1591125180.151 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:00.170Z,1591125180.170 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2020-06-02T19:13:00.170Z,1591125180.170 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:00.171Z,1591125180.171 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9615 2020-06-02T19:13:00.558Z,1591125180.558 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-02T19:13:00.558Z,1591125180.558 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-06-02T19:13:00.559Z,1591125180.559 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:00.574Z,1591125180.574 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-06-02T19:13:00.574Z,1591125180.574 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:00.575Z,1591125180.575 [Radio_Surface](INFO): Join timeout helper Thread ID is 9616 2020-06-02T19:13:00.919Z,1591125180.919 [Radio_Surface](INFO): Powering down 2020-06-02T19:13:00.920Z,1591125180.920 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-02T19:13:00.920Z,1591125180.920 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:00.922Z,1591125180.922 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-06-02T19:13:00.922Z,1591125180.922 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:00.923Z,1591125180.923 [DataOverHttps](INFO): Join timeout helper Thread ID is 9617 2020-06-02T19:13:01.715Z,1591125181.715 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-02T19:13:01.716Z,1591125181.716 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:01.729Z,1591125181.729 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-06-02T19:13:01.729Z,1591125181.729 [logger ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:01.729Z,1591125181.729 [logger](INFO): Join timeout helper Thread ID is 9618 2020-06-02T19:13:01.754Z,1591125181.754 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-02T19:13:01.755Z,1591125181.755 [logger ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:01.767Z,1591125181.767 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-06-02T19:13:01.767Z,1591125181.767 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:01.767Z,1591125181.767 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-06-02T19:13:01.767Z,1591125181.767 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:01.768Z,1591125181.768 [controlThread](INFO): Join timeout helper Thread ID is 9619 2020-06-02T19:13:01.974Z,1591125181.974 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-02T19:13:01.975Z,1591125181.975 [controlThread](DEBUG): Uninitializing ControlThread 2020-06-02T19:13:01.975Z,1591125181.975 [AHRS_M2](INFO): Powering down 2020-06-02T19:13:02.047Z,1591125182.047 [DDM](INFO): Powering down 2020-06-02T19:13:02.139Z,1591125182.139 [DUSBL_Hydroid](INFO): Powering down 2020-06-02T19:13:02.231Z,1591125182.231 [Micromodem](INFO): Powering down 2020-06-02T19:13:02.327Z,1591125182.327 [NAL9602](INFO): Powering down 2020-06-02T19:13:02.398Z,1591125182.398 [RDI_Pathfinder](INFO): Powering down 2020-06-02T19:13:02.470Z,1591125182.470 [RDI_PathfinderUp](INFO): Powering down 2020-06-02T19:13:02.472Z,1591125182.472 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-06-02T19:13:02.473Z,1591125182.473 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-06-02T19:13:02.473Z,1591125182.473 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-06-02T19:13:02.474Z,1591125182.474 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2020-06-02T19:13:02.474Z,1591125182.474 [DefaultWithUndock] Stopped 2020-06-02T19:13:02.474Z,1591125182.474 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2020-06-02T19:13:02.474Z,1591125182.474 [DefaultWithUndock:C.GoToSurface] Stopped 2020-06-02T19:13:02.474Z,1591125182.474 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-06-02T19:13:02.474Z,1591125182.474 [DefaultWithUndock:CheckIn] Stopped 2020-06-02T19:13:02.474Z,1591125182.474 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-06-02T19:13:02.475Z,1591125182.475 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-06-02T19:13:02.475Z,1591125182.475 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-02T19:13:02.478Z,1591125182.478 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-06-02T19:13:02.478Z,1591125182.478 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-06-02T19:13:02.479Z,1591125182.479 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-06-02T19:13:02.479Z,1591125182.479 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-06-02T19:13:02.479Z,1591125182.479 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-02T19:13:02.479Z,1591125182.479 [BuoyancyServo](INFO): Powering down 2020-06-02T19:13:02.490Z,1591125182.490 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-06-02T19:13:02.490Z,1591125182.490 [ElevatorServo](INFO): Powering down 2020-06-02T19:13:02.491Z,1591125182.491 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-06-02T19:13:02.491Z,1591125182.491 [MassServo](INFO): Powering down 2020-06-02T19:13:02.492Z,1591125182.492 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-06-02T19:13:02.492Z,1591125182.492 [RudderServo](INFO): Powering down 2020-06-02T19:13:02.493Z,1591125182.493 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-06-02T19:13:02.493Z,1591125182.493 [ThrusterServo](INFO): Powering down 2020-06-02T19:13:02.494Z,1591125182.494 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-06-02T19:13:02.494Z,1591125182.494 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-06-02T19:13:02.495Z,1591125182.495 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-06-02T19:13:02.495Z,1591125182.495 [CBIT](DEBUG): Powering off loads. 2020-06-02T19:13:02.506Z,1591125182.506 [CBIT](DEBUG): Disabling WDT. 2020-06-02T19:13:02.518Z,1591125182.518 [CBIT](DEBUG): Opening all GF detection circuits. 2020-06-02T19:13:02.519Z,1591125182.519 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:02.566Z,1591125182.566 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:02.577Z,1591125182.577 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:02.626Z,1591125182.626 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:02.688Z,1591125182.688 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-02T19:13:02.757Z,1591125182.757 [logger ThreadHandler](INFO): Thread cancelled.