2017-06-06T20:05:29.918Z,1496779529.918 [NAL9602](INFO): Received command:restart logs 2017-06-06T20:05:29.925Z,1496779529.925 [CommandLine](IMPORTANT): got command restart logs 2017-06-06T20:05:35.429Z,1496779535.429 [NAL9602](INFO): Powering down 2017-06-06T20:07:47.264Z,1496779667.264 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:07:47.264Z,1496779667.264 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:07:47.264Z,1496779667.264 [BPC1](ERROR): Communications Fault 2017-06-06T20:07:47.305Z,1496779667.305 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:07:48.505Z,1496779668.505 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:07:48.506Z,1496779668.506 [BPC1] No Fault, FailCount= 1 2017-06-06T20:08:31.045Z,1496779711.045 [CBIT](DEBUG): EFC running - data check-sum false 2017-06-06T20:08:57.906Z,1496779737.906 [BPC1](ERROR): Failed to parse message. 2017-06-06T20:08:57.907Z,1496779737.907 [BPC1](ERROR): Failed to parse bank B battery data 2017-06-06T20:08:57.907Z,1496779737.907 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:08:57.907Z,1496779737.907 [BPC1](ERROR): Data Fault 2017-06-06T20:08:57.931Z,1496779737.931 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:08:59.137Z,1496779739.137 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:08:59.137Z,1496779739.137 [BPC1] No Fault, FailCount= 1 2017-06-06T20:09:25.105Z,1496779765.105 [BPC1](ERROR): Failed to parse message. 2017-06-06T20:09:25.105Z,1496779765.105 [BPC1](ERROR): Failed to parse bank B battery data 2017-06-06T20:09:25.106Z,1496779765.106 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:09:25.106Z,1496779765.106 [BPC1](ERROR): Data Fault 2017-06-06T20:09:25.158Z,1496779765.158 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:09:26.335Z,1496779766.335 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:09:26.335Z,1496779766.335 [BPC1] No Fault, FailCount= 1 2017-06-06T20:10:26.182Z,1496779826.182 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2017-06-06T20:10:26.182Z,1496779826.182 [Default:CheckIn:C.Wait] Stopped 2017-06-06T20:10:26.182Z,1496779826.182 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2017-06-06T20:10:26.182Z,1496779826.182 [Default:CheckIn:D] Running Loop=1 2017-06-06T20:10:26.603Z,1496779826.603 [Default:CheckIn:D] Stopped 2017-06-06T20:10:26.603Z,1496779826.603 [Default:CheckIn:E] Running Loop=1 2017-06-06T20:10:27.007Z,1496779827.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.055294 min 2017-06-06T20:10:27.007Z,1496779827.007 [Default:CheckIn:E] Stopped 2017-06-06T20:10:27.007Z,1496779827.007 [Default:CheckIn](INFO): Completed Default:CheckIn 2017-06-06T20:10:27.007Z,1496779827.007 [Default:CheckIn] Stopped 2017-06-06T20:10:27.007Z,1496779827.007 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2017-06-06T20:10:27.008Z,1496779827.008 [Default:CheckIn](INFO): Running loop #4 2017-06-06T20:10:27.008Z,1496779827.008 [Default:CheckIn] Running Loop=4 2017-06-06T20:10:27.008Z,1496779827.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-06-06T20:10:27.008Z,1496779827.008 [Default:CheckIn:Read_GPS] Running Loop=1 2017-06-06T20:10:30.805Z,1496779830.805 [NAL9602](INFO): Powering up 2017-06-06T20:10:41.698Z,1496779841.698 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:10:42.495Z,1496779842.495 [NAL9602](FAULT): GPS failed to acquire within timeout. 2017-06-06T20:10:42.495Z,1496779842.495 [NAL9602] Data Fault, FailCount= 3 2017-06-06T20:10:42.496Z,1496779842.496 [NAL9602](ERROR): Data Fault 2017-06-06T20:10:42.637Z,1496779842.637 [CBIT](ERROR): Data Fault in component: NAL9602 2017-06-06T20:10:42.900Z,1496779842.900 [NAL9602](INFO): Powering down 2017-06-06T20:10:43.763Z,1496779843.763 [CBIT](INFO): Clearing failed state for component NAL9602 2017-06-06T20:10:43.764Z,1496779843.764 [NAL9602] No Fault, FailCount= 3 2017-06-06T20:10:48.110Z,1496779848.110 [NAL9602](INFO): Powering up NAL9602 2017-06-06T20:10:59.308Z,1496779859.308 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:12:06.312Z,1496779926.312 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:12:06.312Z,1496779926.312 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:12:06.312Z,1496779926.312 [BPC1](ERROR): Communications Fault 2017-06-06T20:12:06.367Z,1496779926.367 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:12:07.587Z,1496779927.587 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:12:07.587Z,1496779927.587 [BPC1] No Fault, FailCount= 1 2017-06-06T20:12:26.682Z,1496779946.682 [NAL9602](INFO): SBD MO Status=0, MOMSN=64923, MT Status=0, MTMSN=0 2017-06-06T20:12:26.682Z,1496779946.682 [NAL9602](INFO): No messages in MT queue 2017-06-06T20:12:27.886Z,1496779947.886 [NAL9602](INFO): GPS fix at 20170606T201148: (36.807784, -121.785333) 2017-06-06T20:12:28.004Z,1496779948.004 [Default:CheckIn:Read_GPS] Stopped 2017-06-06T20:12:28.004Z,1496779948.004 [Default:CheckIn:Read_Iridium] Running Loop=1 2017-06-06T20:12:31.828Z,1496779951.828 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20170606T193802/Courier0016.lzma 2017-06-06T20:12:32.566Z,1496779952.566 [DataOverHttps](INFO): Moved sent file to Logs/20170606T193802/Courier0016.lzma.bak 2017-06-06T20:12:32.566Z,1496779952.566 [DataOverHttps](INFO): SBD MOMSN=5045942 2017-06-06T20:12:37.669Z,1496779957.669 [NAL9602](INFO): Powering down 2017-06-06T20:12:41.855Z,1496779961.855 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20170606T200529/Courier0000.lzma 2017-06-06T20:12:42.635Z,1496779962.635 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Courier0000.lzma.bak 2017-06-06T20:12:42.635Z,1496779962.635 [DataOverHttps](INFO): SBD MOMSN=5045944 2017-06-06T20:12:52.158Z,1496779972.158 [DataOverHttps](INFO): Sending 108 bytes from file Logs/20170606T193802/Express0017.lzma 2017-06-06T20:12:52.935Z,1496779972.935 [DataOverHttps](INFO): Moved sent file to Logs/20170606T193802/Express0017.lzma.bak 2017-06-06T20:12:52.935Z,1496779972.935 [DataOverHttps](INFO): SBD MOMSN=5045948 2017-06-06T20:13:04.047Z,1496779984.047 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20170606T200529/Express0001.lzma 2017-06-06T20:13:04.826Z,1496779984.826 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Express0001.lzma.bak 2017-06-06T20:13:04.826Z,1496779984.826 [DataOverHttps](INFO): SBD MOMSN=5045951 2017-06-06T20:13:05.397Z,1496779985.397 [Default:CheckIn:Read_Iridium] Stopped 2017-06-06T20:13:05.397Z,1496779985.397 [Default:CheckIn:C.Wait] Running Loop=1 2017-06-06T20:13:05.397Z,1496779985.397 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2017-06-06T20:14:30.652Z,1496780070.652 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:14:30.652Z,1496780070.652 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:14:30.652Z,1496780070.652 [BPC1](ERROR): Communications Fault 2017-06-06T20:14:30.729Z,1496780070.729 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:14:31.898Z,1496780071.898 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:14:31.898Z,1496780071.898 [BPC1] No Fault, FailCount= 1 2017-06-06T20:15:12.045Z,1496780112.045 [PNI_TCM](ERROR): CRC does not match. Expected:0x38007 got:0x6335 2017-06-06T20:15:24.852Z,1496780124.852 [PNI_TCM](ERROR): CRC does not match. Expected:0x59736 got:0x16724 2017-06-06T20:15:25.645Z,1496780125.645 [PNI_TCM](ERROR): CRC does not match. Expected:0x40087 got:0x7233 2017-06-06T20:15:26.448Z,1496780126.448 [PNI_TCM](ERROR): CRC does not match. Expected:0x47588 got:0x16428 2017-06-06T20:15:29.645Z,1496780129.645 [PNI_TCM](ERROR): CRC does not match. Expected:0x28082 got:0x62488 2017-06-06T20:15:33.633Z,1496780133.633 [PNI_TCM](ERROR): CRC does not match. Expected:0x25203 got:0x35238 2017-06-06T20:16:04.847Z,1496780164.847 [PNI_TCM](ERROR): CRC does not match. Expected:0x58647 got:0x53097 2017-06-06T20:16:09.341Z,1496780169.341 [PNI_TCM](ERROR): CRC does not match. Expected:0x9550 got:0x6465 2017-06-06T20:16:09.342Z,1496780169.342 [PNI_TCM](ERROR): CRC does not match. Expected:0x13553 got:0x6465 2017-06-06T20:16:09.342Z,1496780169.342 [PNI_TCM](ERROR): CRC does not match. Expected:0x52868 got:0x6465 2017-06-06T20:16:09.343Z,1496780169.343 [PNI_TCM](ERROR): CRC does not match. Expected:0x16736 got:0x6465 2017-06-06T20:16:09.343Z,1496780169.343 [PNI_TCM](ERROR): CRC does not match. Expected:0x31643 got:0x6465 2017-06-06T20:16:09.343Z,1496780169.343 [PNI_TCM](ERROR): CRC does not match. Expected:0x29160 got:0x6465 2017-06-06T20:16:09.344Z,1496780169.344 [PNI_TCM](ERROR): CRC does not match. Expected:0x3149 got:0x6465 2017-06-06T20:16:09.344Z,1496780169.344 [PNI_TCM](ERROR): CRC does not match. Expected:0x57826 got:0x6465 2017-06-06T20:17:26.594Z,1496780246.594 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:17:26.594Z,1496780246.594 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:17:26.594Z,1496780246.594 [BPC1](ERROR): Communications Fault 2017-06-06T20:17:26.647Z,1496780246.647 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:17:27.832Z,1496780247.832 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:17:27.832Z,1496780247.832 [BPC1] No Fault, FailCount= 1 2017-06-06T20:18:05.809Z,1496780285.809 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2017-06-06T20:18:05.809Z,1496780285.809 [Default:CheckIn:C.Wait] Stopped 2017-06-06T20:18:05.809Z,1496780285.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2017-06-06T20:18:05.809Z,1496780285.809 [Default:CheckIn:D] Running Loop=1 2017-06-06T20:18:06.228Z,1496780286.228 [Default:CheckIn:D] Stopped 2017-06-06T20:18:06.228Z,1496780286.228 [Default:CheckIn:E] Running Loop=1 2017-06-06T20:18:06.624Z,1496780286.624 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.715633 min 2017-06-06T20:18:06.624Z,1496780286.624 [Default:CheckIn:E] Stopped 2017-06-06T20:18:06.624Z,1496780286.624 [Default:CheckIn](INFO): Completed Default:CheckIn 2017-06-06T20:18:06.624Z,1496780286.624 [Default:CheckIn] Stopped 2017-06-06T20:18:06.625Z,1496780286.625 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2017-06-06T20:18:06.625Z,1496780286.625 [Default:CheckIn](INFO): Running loop #5 2017-06-06T20:18:06.625Z,1496780286.625 [Default:CheckIn] Running Loop=5 2017-06-06T20:18:06.625Z,1496780286.625 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-06-06T20:18:06.625Z,1496780286.625 [Default:CheckIn:Read_GPS] Running Loop=1 2017-06-06T20:18:10.253Z,1496780290.253 [NAL9602](INFO): Powering up 2017-06-06T20:18:21.393Z,1496780301.393 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:18:31.086Z,1496780311.086 [CBIT](DEBUG): EFC running - data check-sum false 2017-06-06T20:19:22.382Z,1496780362.382 [BPC1](ERROR): Failed to parse message. 2017-06-06T20:19:22.382Z,1496780362.382 [BPC1](ERROR): Failed to parse bank B battery data 2017-06-06T20:19:22.382Z,1496780362.382 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:19:22.382Z,1496780362.382 [BPC1](ERROR): Data Fault 2017-06-06T20:19:22.442Z,1496780362.442 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:19:23.592Z,1496780363.592 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:19:23.592Z,1496780363.592 [BPC1] No Fault, FailCount= 1 2017-06-06T20:21:06.327Z,1496780466.327 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T20:21:06.327Z,1496780466.327 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:21:06.327Z,1496780466.327 [BPC1](ERROR): Data Fault 2017-06-06T20:21:06.375Z,1496780466.375 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:21:10.643Z,1496780470.643 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:21:10.643Z,1496780470.643 [BPC1] No Fault, FailCount= 1 2017-06-06T20:21:55.103Z,1496780515.103 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:21:55.103Z,1496780515.103 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:21:55.103Z,1496780515.103 [BPC1](ERROR): Communications Fault 2017-06-06T20:21:55.171Z,1496780515.171 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:21:56.342Z,1496780516.342 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:21:56.342Z,1496780516.342 [BPC1] No Fault, FailCount= 1 2017-06-06T20:22:12.147Z,1496780532.147 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:22:12.147Z,1496780532.147 [BPC1] Communications Fault, FailCount= 2 2017-06-06T20:22:12.147Z,1496780532.147 [BPC1](ERROR): Communications Fault 2017-06-06T20:22:12.203Z,1496780532.203 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:22:13.369Z,1496780533.369 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:22:13.369Z,1496780533.369 [BPC1] No Fault, FailCount= 2 2017-06-06T20:22:37.735Z,1496780557.735 [BPC1](ERROR): Failed to parse message. 2017-06-06T20:22:37.735Z,1496780557.735 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T20:22:37.735Z,1496780557.735 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:22:37.735Z,1496780557.735 [BPC1](ERROR): Data Fault 2017-06-06T20:22:37.800Z,1496780557.800 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:22:38.981Z,1496780558.981 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:22:38.981Z,1496780558.981 [BPC1] No Fault, FailCount= 1 2017-06-06T20:23:06.956Z,1496780586.956 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-06-06T20:18:06.6Z 2017-06-06T20:23:06.956Z,1496780586.956 [Default:CheckIn:Read_GPS] Stopped 2017-06-06T20:23:06.956Z,1496780586.956 [Default:CheckIn:Read_Iridium] Running Loop=1 2017-06-06T20:23:11.170Z,1496780591.170 [DataOverHttps](INFO): Sending 46 bytes from file Logs/20170606T200529/Courier0004.lzma 2017-06-06T20:23:11.925Z,1496780591.925 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Courier0004.lzma.bak 2017-06-06T20:23:11.925Z,1496780591.925 [DataOverHttps](INFO): SBD MOMSN=5045957 2017-06-06T20:23:21.334Z,1496780601.334 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20170606T200529/Express0005.lzma 2017-06-06T20:23:22.101Z,1496780602.101 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Express0005.lzma.bak 2017-06-06T20:23:22.101Z,1496780602.101 [DataOverHttps](INFO): SBD MOMSN=5045959 2017-06-06T20:23:22.711Z,1496780602.711 [Default:CheckIn:Read_Iridium] Stopped 2017-06-06T20:23:22.711Z,1496780602.711 [Default:CheckIn:C.Wait] Running Loop=1 2017-06-06T20:23:22.711Z,1496780602.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2017-06-06T20:23:26.243Z,1496780606.243 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2017-06-06T20:23:31.595Z,1496780611.595 [NAL9602](INFO): Powering down 2017-06-06T20:26:58.806Z,1496780818.806 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:26:58.806Z,1496780818.806 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:26:58.806Z,1496780818.806 [BPC1](ERROR): Communications Fault 2017-06-06T20:26:58.862Z,1496780818.862 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:27:00.045Z,1496780820.045 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:27:00.045Z,1496780820.045 [BPC1] No Fault, FailCount= 1 2017-06-06T20:28:23.222Z,1496780903.222 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2017-06-06T20:28:23.223Z,1496780903.223 [Default:CheckIn:C.Wait] Stopped 2017-06-06T20:28:23.223Z,1496780903.223 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2017-06-06T20:28:23.223Z,1496780903.223 [Default:CheckIn:D] Running Loop=1 2017-06-06T20:28:23.617Z,1496780903.617 [Default:CheckIn:D] Stopped 2017-06-06T20:28:23.617Z,1496780903.617 [Default:CheckIn:E] Running Loop=1 2017-06-06T20:28:24.002Z,1496780904.002 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.005534 min 2017-06-06T20:28:24.002Z,1496780904.002 [Default:CheckIn:E] Stopped 2017-06-06T20:28:24.002Z,1496780904.002 [Default:CheckIn](INFO): Completed Default:CheckIn 2017-06-06T20:28:24.002Z,1496780904.002 [Default:CheckIn] Stopped 2017-06-06T20:28:24.002Z,1496780904.002 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2017-06-06T20:28:24.003Z,1496780904.003 [Default:CheckIn](INFO): Running loop #6 2017-06-06T20:28:24.003Z,1496780904.003 [Default:CheckIn] Running Loop=6 2017-06-06T20:28:24.003Z,1496780904.003 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-06-06T20:28:24.003Z,1496780904.003 [Default:CheckIn:Read_GPS] Running Loop=1 2017-06-06T20:28:27.632Z,1496780907.632 [NAL9602](INFO): Powering up 2017-06-06T20:28:31.442Z,1496780911.442 [CBIT](DEBUG): EFC running - data check-sum false 2017-06-06T20:28:38.581Z,1496780918.581 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:28:39.386Z,1496780919.386 [NAL9602](FAULT): GPS failed to acquire within timeout. 2017-06-06T20:28:39.386Z,1496780919.386 [NAL9602] Data Fault, FailCount= 1 2017-06-06T20:28:39.386Z,1496780919.386 [NAL9602](ERROR): Data Fault 2017-06-06T20:28:39.494Z,1496780919.494 [CBIT](ERROR): Data Fault in component: NAL9602 2017-06-06T20:28:39.783Z,1496780919.783 [NAL9602](INFO): Powering down 2017-06-06T20:28:40.621Z,1496780920.621 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T20:28:40.622Z,1496780920.622 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:28:40.622Z,1496780920.622 [BPC1](ERROR): Data Fault 2017-06-06T20:28:40.702Z,1496780920.702 [CBIT](INFO): Clearing failed state for component NAL9602 2017-06-06T20:28:40.702Z,1496780920.702 [NAL9602] No Fault, FailCount= 1 2017-06-06T20:28:40.703Z,1496780920.703 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:28:41.919Z,1496780921.919 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:28:41.919Z,1496780921.919 [BPC1] No Fault, FailCount= 1 2017-06-06T20:28:44.993Z,1496780924.993 [NAL9602](INFO): Powering up NAL9602 2017-06-06T20:28:56.193Z,1496780936.193 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:29:53.248Z,1496780993.248 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T20:29:53.248Z,1496780993.248 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:29:53.248Z,1496780993.248 [BPC1](ERROR): Data Fault 2017-06-06T20:29:53.325Z,1496780993.325 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:29:54.503Z,1496780994.503 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:29:54.503Z,1496780994.503 [BPC1] No Fault, FailCount= 1 2017-06-06T20:29:59.262Z,1496780999.262 [PNI_TCM](ERROR): CRC does not match. Expected:0x35635 got:0x54296 2017-06-06T20:30:00.071Z,1496781000.071 [PNI_TCM](ERROR): CRC does not match. Expected:0x27857 got:0x6593 2017-06-06T20:30:09.663Z,1496781009.663 [PNI_TCM](ERROR): CRC does not match. Expected:0x10936 got:0x17180 2017-06-06T20:30:10.059Z,1496781010.059 [PNI_TCM](ERROR): CRC does not match. Expected:0x52395 got:0x49138 2017-06-06T20:30:10.466Z,1496781010.466 [PNI_TCM](ERROR): CRC does not match. Expected:0x30564 got:0x37383 2017-06-06T20:31:17.049Z,1496781077.049 [NAL9602](INFO): SBD MO Status=2, MOMSN=64924, MT Status=2, MTMSN=0 2017-06-06T20:31:17.049Z,1496781077.049 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2017-06-06T20:31:34.866Z,1496781094.866 [PNI_TCM](ERROR): CRC does not match. Expected:0x7086 got:0x17206 2017-06-06T20:31:37.669Z,1496781097.669 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:31:37.669Z,1496781097.669 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:31:37.669Z,1496781097.669 [BPC1](ERROR): Communications Fault 2017-06-06T20:31:37.722Z,1496781097.722 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:31:38.896Z,1496781098.896 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:31:38.896Z,1496781098.896 [BPC1] No Fault, FailCount= 1 2017-06-06T20:33:24.236Z,1496781204.236 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-06-06T20:28:24.0Z 2017-06-06T20:33:24.236Z,1496781204.236 [Default:CheckIn:Read_GPS] Stopped 2017-06-06T20:33:24.236Z,1496781204.236 [Default:CheckIn:Read_Iridium] Running Loop=1 2017-06-06T20:33:28.931Z,1496781208.931 [DataOverHttps](INFO): Sending 46 bytes from file Logs/20170606T200529/Courier0008.lzma 2017-06-06T20:33:29.694Z,1496781209.694 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Courier0008.lzma.bak 2017-06-06T20:33:29.694Z,1496781209.694 [DataOverHttps](INFO): SBD MOMSN=5045962 2017-06-06T20:33:38.979Z,1496781218.979 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20170606T200529/Express0009.lzma 2017-06-06T20:33:39.730Z,1496781219.730 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Express0009.lzma.bak 2017-06-06T20:33:39.730Z,1496781219.730 [DataOverHttps](INFO): SBD MOMSN=5045964 2017-06-06T20:33:40.290Z,1496781220.290 [Default:CheckIn:Read_Iridium] Stopped 2017-06-06T20:33:40.290Z,1496781220.290 [Default:CheckIn:C.Wait] Running Loop=1 2017-06-06T20:33:40.290Z,1496781220.290 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2017-06-06T20:33:59.556Z,1496781239.556 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2017-06-06T20:34:05.157Z,1496781245.157 [NAL9602](INFO): Powering down 2017-06-06T20:36:47.427Z,1496781407.427 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:36:47.427Z,1496781407.427 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:36:47.428Z,1496781407.428 [BPC1](ERROR): Communications Fault 2017-06-06T20:36:47.451Z,1496781407.451 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:36:48.658Z,1496781408.658 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:36:48.658Z,1496781408.658 [BPC1] No Fault, FailCount= 1 2017-06-06T20:38:31.475Z,1496781511.475 [CBIT](DEBUG): EFC running - data check-sum false 2017-06-06T20:38:40.702Z,1496781520.702 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2017-06-06T20:38:40.702Z,1496781520.702 [Default:CheckIn:C.Wait] Stopped 2017-06-06T20:38:40.702Z,1496781520.702 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2017-06-06T20:38:40.702Z,1496781520.702 [Default:CheckIn:D] Running Loop=1 2017-06-06T20:38:41.114Z,1496781521.114 [Default:CheckIn:D] Stopped 2017-06-06T20:38:41.114Z,1496781521.114 [Default:CheckIn:E] Running Loop=1 2017-06-06T20:38:41.491Z,1496781521.491 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.297148 min 2017-06-06T20:38:41.491Z,1496781521.491 [Default:CheckIn:E] Stopped 2017-06-06T20:38:41.492Z,1496781521.492 [Default:CheckIn](INFO): Completed Default:CheckIn 2017-06-06T20:38:41.492Z,1496781521.492 [Default:CheckIn] Stopped 2017-06-06T20:38:41.492Z,1496781521.492 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2017-06-06T20:38:41.492Z,1496781521.492 [Default:CheckIn](INFO): Running loop #7 2017-06-06T20:38:41.492Z,1496781521.492 [Default:CheckIn] Running Loop=7 2017-06-06T20:38:41.492Z,1496781521.492 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-06-06T20:38:41.492Z,1496781521.492 [Default:CheckIn:Read_GPS] Running Loop=1 2017-06-06T20:38:45.281Z,1496781525.281 [NAL9602](INFO): Powering up 2017-06-06T20:38:54.493Z,1496781534.493 [PNI_TCM](ERROR): CRC does not match. Expected:0x26881 got:0x3642 2017-06-06T20:38:56.475Z,1496781536.475 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:38:57.267Z,1496781537.267 [NAL9602](FAULT): GPS failed to acquire within timeout. 2017-06-06T20:38:57.267Z,1496781537.267 [NAL9602] Data Fault, FailCount= 2 2017-06-06T20:38:57.267Z,1496781537.267 [NAL9602](ERROR): Data Fault 2017-06-06T20:38:57.362Z,1496781537.362 [CBIT](ERROR): Data Fault in component: NAL9602 2017-06-06T20:38:57.672Z,1496781537.672 [NAL9602](INFO): Powering down 2017-06-06T20:38:57.714Z,1496781537.714 [PNI_TCM](ERROR): CRC does not match. Expected:0x547 got:0x225 2017-06-06T20:38:58.574Z,1496781538.574 [CBIT](INFO): Clearing failed state for component NAL9602 2017-06-06T20:38:58.574Z,1496781538.574 [NAL9602] No Fault, FailCount= 2 2017-06-06T20:38:59.298Z,1496781539.298 [PNI_TCM](ERROR): CRC does not match. Expected:0x13969 got:0x32768 2017-06-06T20:39:02.864Z,1496781542.864 [NAL9602](INFO): Powering up NAL9602 2017-06-06T20:39:08.903Z,1496781548.903 [PNI_TCM](ERROR): CRC does not match. Expected:0x60383 got:0x26112 2017-06-06T20:39:13.662Z,1496781553.662 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:39:14.086Z,1496781554.086 [PNI_TCM](ERROR): CRC does not match. Expected:0x19404 got:0x32768 2017-06-06T20:39:17.299Z,1496781557.299 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:39:17.299Z,1496781557.299 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:39:17.299Z,1496781557.299 [BPC1](ERROR): Communications Fault 2017-06-06T20:39:17.340Z,1496781557.340 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:39:18.493Z,1496781558.493 [PNI_TCM](ERROR): CRC does not match. Expected:0x31902 got:0x796 2017-06-06T20:39:18.546Z,1496781558.546 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:39:18.546Z,1496781558.546 [BPC1] No Fault, FailCount= 1 2017-06-06T20:39:20.098Z,1496781560.098 [PNI_TCM](ERROR): CRC does not match. Expected:0x20647 got:0x7490 2017-06-06T20:39:31.311Z,1496781571.311 [PNI_TCM](ERROR): CRC does not match. Expected:0x24894 got:0x27815 2017-06-06T20:39:34.491Z,1496781574.491 [PNI_TCM](ERROR): CRC does not match. Expected:0x1761 got:0x49574 2017-06-06T20:39:39.304Z,1496781579.304 [PNI_TCM](ERROR): CRC does not match. Expected:0x5904 got:0x49272 2017-06-06T20:39:40.896Z,1496781580.896 [PNI_TCM](ERROR): CRC does not match. Expected:0x6762 got:0x42600 2017-06-06T20:41:31.675Z,1496781691.675 [BPC1](ERROR): Failed to receive battery data 2017-06-06T20:41:31.675Z,1496781691.675 [BPC1] Communications Fault, FailCount= 1 2017-06-06T20:41:31.675Z,1496781691.675 [BPC1](ERROR): Communications Fault 2017-06-06T20:41:31.758Z,1496781691.758 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T20:41:32.898Z,1496781692.898 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:41:32.898Z,1496781692.898 [BPC1] No Fault, FailCount= 1 2017-06-06T20:43:41.901Z,1496781821.901 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-06-06T20:38:41.5Z 2017-06-06T20:43:41.901Z,1496781821.901 [Default:CheckIn:Read_GPS] Stopped 2017-06-06T20:43:41.901Z,1496781821.901 [Default:CheckIn:Read_Iridium] Running Loop=1 2017-06-06T20:43:46.131Z,1496781826.131 [DataOverHttps](INFO): Sending 46 bytes from file Logs/20170606T200529/Courier0012.lzma 2017-06-06T20:43:46.895Z,1496781826.895 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Courier0012.lzma.bak 2017-06-06T20:43:46.896Z,1496781826.896 [DataOverHttps](INFO): SBD MOMSN=5045969 2017-06-06T20:43:56.079Z,1496781836.079 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20170606T200529/Express0013.lzma 2017-06-06T20:43:56.849Z,1496781836.849 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Express0013.lzma.bak 2017-06-06T20:43:56.850Z,1496781836.850 [DataOverHttps](INFO): SBD MOMSN=5045971 2017-06-06T20:43:57.438Z,1496781837.438 [Default:CheckIn:Read_Iridium] Stopped 2017-06-06T20:43:57.438Z,1496781837.438 [Default:CheckIn:C.Wait] Running Loop=1 2017-06-06T20:43:57.438Z,1496781837.438 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2017-06-06T20:44:16.320Z,1496781856.320 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2017-06-06T20:44:21.920Z,1496781861.920 [NAL9602](INFO): Powering down 2017-06-06T20:44:45.982Z,1496781885.982 [BPC1](ERROR): Failed to parse bank B battery data 2017-06-06T20:44:45.982Z,1496781885.982 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:44:45.982Z,1496781885.982 [BPC1](ERROR): Data Fault 2017-06-06T20:44:46.037Z,1496781886.037 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:44:47.191Z,1496781887.191 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:44:47.192Z,1496781887.192 [BPC1] No Fault, FailCount= 1 2017-06-06T20:46:02.396Z,1496781962.396 [DataOverHttps](IMPORTANT): SBD MTMSN=20170606T204524 2017-06-06T20:46:06.601Z,1496781966.601 [DataOverHttps](INFO): Received command:load Maintenance/sample.xml;set sample.NumberOfSamples 3 count;set sample.WaitBeforeSample 1 minute;set sample:SampleAtDepth.TargetDepth 7 meter;run 2017-06-06T20:46:06.620Z,1496781966.620 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml 2017-06-06T20:46:06.621Z,1496781966.621 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml 2017-06-06T20:46:06.675Z,1496781966.675 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min 2017-06-06T20:46:06.679Z,1496781966.679 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m 2017-06-06T20:46:06.689Z,1496781966.689 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count 2017-06-06T20:46:06.693Z,1496781966.693 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min 2017-06-06T20:46:06.694Z,1496781966.694 [sample:A.Pitch](DEBUG): Construct. 2017-06-06T20:46:06.717Z,1496781966.717 [sample:B.SetSpeed](DEBUG): Construct. 2017-06-06T20:46:06.720Z,1496781966.720 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml 2017-06-06T20:46:06.840Z,1496781966.840 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m 2017-06-06T20:46:06.843Z,1496781966.843 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 30.000000 s 2017-06-06T20:46:06.846Z,1496781966.846 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 1.000000 bool 2017-06-06T20:46:06.860Z,1496781966.860 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 0.000000 bool 2017-06-06T20:46:06.863Z,1496781966.863 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min 2017-06-06T20:46:06.867Z,1496781966.867 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min 2017-06-06T20:46:06.885Z,1496781966.885 [sample:SampleAtDepth:B.Pitch](DEBUG): Construct. 2017-06-06T20:46:06.894Z,1496781966.894 [sample:SampleAtDepth:D.Wait](DEBUG): Construct Wait. 2017-06-06T20:46:06.925Z,1496781966.925 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute. 2017-06-06T20:46:06.949Z,1496781966.949 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout:B.Execute](DEBUG): Construct Execute. 2017-06-06T20:46:06.986Z,1496781966.986 [MissionManager](DEBUG): This mission is designed to be run in the test tank only and samples whichever sampler is installed once the vehicle reaches the target depth. How long to let the mission run. 90 Depth to sample at. 7 Number of samples to take. 1 How long to wait between samples. This starts *before* the first sample to exercise the SettleTime setting in the insert aggregate. 3 0 2017-06-06T20:46:06.986Z,1496781966.986 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml 2017-06-06T20:46:11.184Z,1496781971.184 [CommandLine](IMPORTANT): got command set sample.NumberOfSamples 3.000000 count 2017-06-06T20:46:11.185Z,1496781971.185 [CommandLine](IMPORTANT): got command set sample.WaitBeforeSample 1.000000 minute 2017-06-06T20:46:11.186Z,1496781971.186 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.TargetDepth 7.000000 meter 2017-06-06T20:46:11.187Z,1496781971.187 [CommandLine](IMPORTANT): got command run 2017-06-06T20:46:11.209Z,1496781971.209 [CommandLine](IMPORTANT): Running 2017-06-06T20:46:11.290Z,1496781971.290 [Default] Stopped 2017-06-06T20:46:11.290Z,1496781971.290 [Default](DEBUG): Aggregate::uninitialize Default 2017-06-06T20:46:11.290Z,1496781971.290 [Default:B.GoToSurface] Stopped 2017-06-06T20:46:11.290Z,1496781971.290 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-06-06T20:46:11.291Z,1496781971.291 [Default:CheckIn] Stopped 2017-06-06T20:46:11.291Z,1496781971.291 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2017-06-06T20:46:11.291Z,1496781971.291 [Default:CheckIn:C.Wait] Stopped 2017-06-06T20:46:11.291Z,1496781971.291 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2017-06-06T20:46:11.296Z,1496781971.296 [MissionManager](IMPORTANT): Started mission sample 2017-06-06T20:46:11.297Z,1496781971.297 [sample] Running Loop=1 2017-06-06T20:46:11.297Z,1496781971.297 [sample](DEBUG): Aggregate::initialize sample 2017-06-06T20:46:11.297Z,1496781971.297 [sample:A.Pitch] Running Loop=1 2017-06-06T20:46:11.297Z,1496781971.297 [sample:A.Pitch](DEBUG): Initialize. 2017-06-06T20:46:11.297Z,1496781971.297 [sample:B.SetSpeed] Running Loop=1 2017-06-06T20:46:11.297Z,1496781971.297 [sample:B.SetSpeed](DEBUG): Initialize. 2017-06-06T20:46:11.298Z,1496781971.298 [sample:D] Running Loop=1 2017-06-06T20:46:11.300Z,1496781971.300 [sample:D] Stopped 2017-06-06T20:46:11.300Z,1496781971.300 [sample:E] Running Loop=1 2017-06-06T20:46:11.300Z,1496781971.300 [sample:B.SetSpeed] Running Loop=1 2017-06-06T20:46:11.313Z,1496781971.313 [sample:A.Pitch] Running Loop=1 2017-06-06T20:46:11.439Z,1496781971.439 [sample:E] Stopped 2017-06-06T20:46:11.439Z,1496781971.439 [sample:SampleRepeater] Running Loop=1 2017-06-06T20:46:11.439Z,1496781971.439 [sample:SampleRepeater](DEBUG): Aggregate::initialize sample:SampleRepeater 2017-06-06T20:46:11.440Z,1496781971.440 [sample:SampleRepeater:Sample] Running Loop=1 2017-06-06T20:46:11.440Z,1496781971.440 [sample:SampleRepeater:Sample](DEBUG): Aggregate::initialize sample:SampleRepeater:Sample 2017-06-06T20:46:11.818Z,1496781971.818 [sample:SampleAtDepth] Running Loop=1 2017-06-06T20:46:11.819Z,1496781971.819 [sample:SampleAtDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth 2017-06-06T20:46:11.819Z,1496781971.819 [sample:SampleAtDepth:B.Pitch] Running Loop=1 2017-06-06T20:46:11.819Z,1496781971.819 [sample:SampleAtDepth:B.Pitch](DEBUG): Initialize. 2017-06-06T20:46:11.819Z,1496781971.819 [sample:SampleAtDepth:A] Running Loop=1 2017-06-06T20:46:11.832Z,1496781971.832 [sample:SampleAtDepth:A](INFO): Moving to 7.000000 m 2017-06-06T20:46:11.834Z,1496781971.834 [sample:SampleAtDepth:A] Stopped 2017-06-06T20:47:32.970Z,1496782052.970 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:47:35.065Z,1496782055.065 [Radio_Surface](INFO): Powering down 2017-06-06T20:47:55.006Z,1496782075.006 [BPC1](ERROR): Failed to parse message. 2017-06-06T20:47:55.006Z,1496782075.006 [BPC1](ERROR): Failed to parse bank B battery data 2017-06-06T20:47:55.006Z,1496782075.006 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:47:55.006Z,1496782075.006 [BPC1](ERROR): Data Fault 2017-06-06T20:47:55.138Z,1496782075.138 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:47:56.272Z,1496782076.272 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:47:56.272Z,1496782076.272 [BPC1] No Fault, FailCount= 1 2017-06-06T20:48:08.206Z,1496782088.206 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T20:48:08.206Z,1496782088.206 [BPC1] Data Fault, FailCount= 2 2017-06-06T20:48:08.206Z,1496782088.206 [BPC1](ERROR): Data Fault 2017-06-06T20:48:08.318Z,1496782088.318 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:48:09.452Z,1496782089.452 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:48:09.453Z,1496782089.453 [BPC1] No Fault, FailCount= 2 2017-06-06T20:48:31.846Z,1496782111.846 [CBIT](DEBUG): EFC running - data check-sum false 2017-06-06T20:48:33.766Z,1496782113.766 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:49:11.762Z,1496782151.762 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.000305 2017-06-06T20:49:58.212Z,1496782198.212 [sample:SampleAtDepth:C] Running Loop=1 2017-06-06T20:49:58.613Z,1496782198.613 [sample:SampleAtDepth:C](INFO): At 7.010823 m , settling for 1.000000 min 2017-06-06T20:49:58.615Z,1496782198.615 [sample:SampleAtDepth:C] Stopped 2017-06-06T20:49:58.615Z,1496782198.615 [sample:SampleAtDepth:D.Wait] Running Loop=1 2017-06-06T20:49:58.615Z,1496782198.615 [sample:SampleAtDepth:D.Wait](DEBUG): Initialize Wait Component. 2017-06-06T20:50:34.164Z,1496782234.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:50:59.031Z,1496782259.031 [sample:SampleAtDepth:D.Wait](INFO): Done Waiting. 2017-06-06T20:50:59.031Z,1496782259.031 [sample:SampleAtDepth:D.Wait] Stopped 2017-06-06T20:50:59.032Z,1496782259.032 [sample:SampleAtDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2017-06-06T20:50:59.032Z,1496782259.032 [sample:SampleAtDepth:SampleWrapper] Running Loop=1 2017-06-06T20:50:59.032Z,1496782259.032 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper 2017-06-06T20:50:59.032Z,1496782259.032 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Running Loop=1 2017-06-06T20:50:59.032Z,1496782259.032 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler 2017-06-06T20:50:59.033Z,1496782259.033 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1 2017-06-06T20:50:59.033Z,1496782259.033 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Running Loop=1 2017-06-06T20:50:59.430Z,1496782259.430 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler](DEBUG): Initialize ReadDataComponent to sense CANONSampler.sampling 2017-06-06T20:50:59.431Z,1496782259.431 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1 2017-06-06T20:51:00.157Z,1496782260.157 [CANONSampler](INFO): Powering up 2017-06-06T20:51:11.043Z,1496782271.043 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Stopped 2017-06-06T20:51:11.043Z,1496782271.043 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:C] Running Loop=1 2017-06-06T20:51:11.359Z,1496782271.359 [CANONSampler](IMPORTANT): UI[1]=2 2017-06-06T20:51:11.359Z,1496782271.359 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:11.359Z,1496782271.359 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:11.360Z,1496782271.360 [CANONSampler](IMPORTANT): S>S 2017-06-06T20:51:11.360Z,1496782271.360 [CANONSampler](IMPORTANT): MO=0 2017-06-06T20:51:11.464Z,1496782271.464 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:C](IMPORTANT): CANONSampler sampling at 7.901181 m in nan degC water with nan ug/l chlorophyll fluorescence. 2017-06-06T20:51:11.465Z,1496782271.465 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:C] Stopped 2017-06-06T20:51:11.466Z,1496782271.466 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D] Running Loop=1 2017-06-06T20:51:11.759Z,1496782271.759 [CANONSampler](IMPORTANT): ELMO will go to position 3 2017-06-06T20:51:11.760Z,1496782271.760 [CANONSampler](IMPORTANT): Initial cnts = 0 2017-06-06T20:51:11.845Z,1496782271.845 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D](DEBUG): Initialize ReadDataComponent to sense CANONSampler.sample_number 2017-06-06T20:51:12.563Z,1496782272.563 [CANONSampler](IMPORTANT): HOMINGMO=1, MF=0, PX=0 2017-06-06T20:51:13.759Z,1496782273.759 [CANONSampler](IMPORTANT): MO=0, MF=0, PX=0 2017-06-06T20:51:13.759Z,1496782273.759 [CANONSampler](IMPORTANT): DONE with MO=0, MF=0 2017-06-06T20:51:18.558Z,1496782278.558 [CANONSampler](IMPORTANT): Pumping DONE 2017-06-06T20:51:19.761Z,1496782279.761 [CANONSampler](IMPORTANT): Next 2017-06-06T20:51:19.762Z,1496782279.762 [CANONSampler](IMPORTANT): MO=0, MF=128, PX=12 2017-06-06T20:51:19.763Z,1496782279.763 [CANONSampler](IMPORTANT): DONE with MO=0, MF=128, PX=12 2017-06-06T20:51:19.763Z,1496782279.763 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:25.357Z,1496782285.357 [CANONSampler](IMPORTANT): Pumping UI[1]ý 2017-06-06T20:51:25.357Z,1496782285.357 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:25.358Z,1496782285.358 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:26.158Z,1496782286.158 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:26.158Z,1496782286.158 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:26.158Z,1496782286.158 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:26.959Z,1496782286.959 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:26.960Z,1496782286.960 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:26.960Z,1496782286.960 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:27.760Z,1496782287.760 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:27.761Z,1496782287.761 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:27.761Z,1496782287.761 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:28.157Z,1496782288.157 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:28.157Z,1496782288.157 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:28.158Z,1496782288.158 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:28.558Z,1496782288.558 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:28.558Z,1496782288.558 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:28.558Z,1496782288.558 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:28.959Z,1496782288.959 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:28.959Z,1496782288.959 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:28.959Z,1496782288.959 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:29.359Z,1496782289.359 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:29.359Z,1496782289.359 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:29.360Z,1496782289.360 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:29.759Z,1496782289.759 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:29.760Z,1496782289.760 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:29.760Z,1496782289.760 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:30.557Z,1496782290.557 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:30.558Z,1496782290.558 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:30.558Z,1496782290.558 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:31.358Z,1496782291.358 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:31.359Z,1496782291.359 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:31.359Z,1496782291.359 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:31.759Z,1496782291.759 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:31.759Z,1496782291.759 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:31.759Z,1496782291.759 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:32.159Z,1496782292.159 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:51:32.560Z,1496782292.560 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:32.561Z,1496782292.561 [CANONSampler](IMPORTANT): UI[1]=3 2017-06-06T20:51:32.957Z,1496782292.957 [CANONSampler](IMPORTANT): Saving.... DONÿUI[1]=3 2017-06-06T20:51:34.164Z,1496782294.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:51:34.559Z,1496782294.559 [CANONSampler](IMPORTANT): Saving.... DÿUI[1]=3 2017-06-06T20:51:40.557Z,1496782300.557 [CANONSampler](IMPORTANT): Saving.... DÏUI[1]=3 2017-06-06T20:51:40.557Z,1496782300.557 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:51:40.557Z,1496782300.557 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:51:56.608Z,1496782316.608 [BPC1](ERROR): Failed to parse message. 2017-06-06T20:51:56.608Z,1496782316.608 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T20:51:56.608Z,1496782316.608 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:51:56.608Z,1496782316.608 [BPC1](ERROR): Data Fault 2017-06-06T20:51:56.720Z,1496782316.720 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:51:57.917Z,1496782317.917 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:51:57.917Z,1496782317.917 [BPC1] No Fault, FailCount= 1 2017-06-06T20:52:34.166Z,1496782354.166 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:52:44.958Z,1496782364.958 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:52:44.959Z,1496782364.959 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:52:44.959Z,1496782364.959 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:52:47.358Z,1496782367.358 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:52:48.160Z,1496782368.160 [CANONSampler](IMPORTANT): Saving.... ôUI[1]=3 2017-06-06T20:52:48.160Z,1496782368.160 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:52:48.160Z,1496782368.160 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:52:54.157Z,1496782374.157 [CANONSampler](IMPORTANT): S>UI[1]=3 2017-06-06T20:52:54.158Z,1496782374.158 [CANONSampler](IMPORTANT): Saving.... DONE 2017-06-06T20:52:54.158Z,1496782374.158 [CANONSampler](IMPORTANT): 1.2 2017-06-06T20:53:34.562Z,1496782414.562 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:54:34.564Z,1496782474.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:55:02.194Z,1496782502.194 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T20:55:02.194Z,1496782502.194 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:55:02.194Z,1496782502.194 [BPC1](ERROR): Data Fault 2017-06-06T20:55:02.297Z,1496782502.297 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:55:03.475Z,1496782503.475 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:55:03.475Z,1496782503.475 [BPC1] No Fault, FailCount= 1 2017-06-06T20:55:34.563Z,1496782534.563 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:55:44.199Z,1496782544.199 [BPC1](ERROR): Failed to parse bank B battery data 2017-06-06T20:55:44.199Z,1496782544.199 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:55:44.199Z,1496782544.199 [BPC1](ERROR): Data Fault 2017-06-06T20:55:44.363Z,1496782544.363 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:55:45.469Z,1496782545.469 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:55:45.470Z,1496782545.470 [BPC1] No Fault, FailCount= 1 2017-06-06T20:56:34.567Z,1496782594.567 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:57:10.961Z,1496782630.961 [CANONSampler](FAULT): Failed to complete sampling within timeout 2017-06-06T20:57:10.961Z,1496782630.961 [CANONSampler] Hardware Fault, FailCount= 1 2017-06-06T20:57:10.961Z,1496782630.961 [CANONSampler](ERROR): Hardware Fault 2017-06-06T20:57:11.114Z,1496782631.114 [CBIT](ERROR): Hardware Fault in component: CANONSampler 2017-06-06T20:57:11.359Z,1496782631.359 [CANONSampler](INFO): Powering down 2017-06-06T20:57:11.535Z,1496782631.535 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D](INFO): Timed out from 2017-06-06T20:51:11.5Z 2017-06-06T20:57:11.535Z,1496782631.535 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout] Running Loop=1 2017-06-06T20:57:11.535Z,1496782631.535 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout 2017-06-06T20:57:11.536Z,1496782631.536 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout:A] Running Loop=1 2017-06-06T20:57:11.536Z,1496782631.536 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout:A](IMPORTANT): Timed out sampling with CANONSampler. Stopping mission. 2017-06-06T20:57:11.541Z,1496782631.541 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout:A] Stopped 2017-06-06T20:57:11.541Z,1496782631.541 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout:B.Execute] Running Loop=1 2017-06-06T20:57:11.846Z,1496782631.846 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout:B.Execute](INFO): Executing command stop 2017-06-06T20:57:11.849Z,1496782631.849 [CommandLine](IMPORTANT): got command stop 2017-06-06T20:57:11.850Z,1496782631.850 [CommandLine](IMPORTANT): Scheduling is paused 2017-06-06T20:57:11.850Z,1496782631.850 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2017-06-06T20:57:11.854Z,1496782631.854 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout:B.Execute] Stopped 2017-06-06T20:57:11.854Z,1496782631.854 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D:A_Timeout 2017-06-06T20:57:11.854Z,1496782631.854 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:D] Stopped 2017-06-06T20:57:11.855Z,1496782631.855 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleCANONSampler 2017-06-06T20:57:11.855Z,1496782631.855 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Stopped 2017-06-06T20:57:11.855Z,1496782631.855 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler 2017-06-06T20:57:11.855Z,1496782631.855 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Stopped 2017-06-06T20:57:11.855Z,1496782631.855 [sample:SampleAtDepth:SampleWrapper:SampleESP] Running Loop=1 2017-06-06T20:57:11.856Z,1496782631.856 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleESP 2017-06-06T20:57:11.856Z,1496782631.856 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Running Loop=1 2017-06-06T20:57:11.857Z,1496782631.857 [MissionManager](INFO): MissionManager is completed. 2017-06-06T20:57:11.857Z,1496782631.857 [MissionManager](INFO): Uninitializing Mission sample 2017-06-06T20:57:11.857Z,1496782631.857 [sample] Stopped 2017-06-06T20:57:11.857Z,1496782631.857 [sample](DEBUG): Aggregate::uninitialize sample 2017-06-06T20:57:11.858Z,1496782631.858 [sample:A.Pitch] Stopped 2017-06-06T20:57:11.858Z,1496782631.858 [sample:B.SetSpeed] Stopped 2017-06-06T20:57:11.858Z,1496782631.858 [sample:B.SetSpeed](DEBUG): Uninitialize. 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleAtDepth] Stopped 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleAtDepth](DEBUG): Aggregate::uninitialize sample:SampleAtDepth 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleAtDepth:B.Pitch] Stopped 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleAtDepth:SampleWrapper] Stopped 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleAtDepth:SampleWrapper:SampleESP] Stopped 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleESP 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Stopped 2017-06-06T20:57:11.858Z,1496782631.858 [sample:SampleRepeater] Stopped 2017-06-06T20:57:11.859Z,1496782631.859 [sample:SampleRepeater](DEBUG): Aggregate::uninitialize sample:SampleRepeater 2017-06-06T20:57:11.859Z,1496782631.859 [sample:SampleRepeater:Sample] Stopped 2017-06-06T20:57:11.859Z,1496782631.859 [sample:SampleRepeater:Sample](DEBUG): Aggregate::uninitialize sample:SampleRepeater:Sample 2017-06-06T20:57:12.223Z,1496782632.223 [MissionManager](IMPORTANT): Started mission Default 2017-06-06T20:57:12.223Z,1496782632.223 [Default] Running Loop=1 2017-06-06T20:57:12.223Z,1496782632.223 [Default](DEBUG): Aggregate::initialize Default 2017-06-06T20:57:12.223Z,1496782632.223 [Default:B.GoToSurface] Running Loop=1 2017-06-06T20:57:12.223Z,1496782632.223 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-06-06T20:57:12.224Z,1496782632.224 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-06-06T20:57:12.224Z,1496782632.224 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-06-06T20:57:12.224Z,1496782632.224 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-06-06T20:57:12.225Z,1496782632.225 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-06-06T20:57:12.225Z,1496782632.225 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-06-06T20:57:12.225Z,1496782632.225 [Default:A.Wait] Running Loop=1 2017-06-06T20:57:12.225Z,1496782632.225 [Default:A.Wait](DEBUG): Initialize Wait Component. 2017-06-06T20:57:12.226Z,1496782632.226 [Default:B.GoToSurface](ERROR): Altitude too low: -3.15 2017-06-06T20:57:12.490Z,1496782632.490 [Radio_Surface](INFO): Powering up 2017-06-06T20:57:12.625Z,1496782632.625 [Default:B.GoToSurface](ERROR): Altitude too low: -3.14 2017-06-06T20:57:13.029Z,1496782633.029 [Default:B.GoToSurface](ERROR): Altitude too low: -3.12 2017-06-06T20:57:13.420Z,1496782633.420 [Default:B.GoToSurface](ERROR): Altitude too low: -3.11 2017-06-06T20:57:13.830Z,1496782633.830 [Default:B.GoToSurface](ERROR): Altitude too low: -3.09 2017-06-06T20:57:14.298Z,1496782634.298 [Default:B.GoToSurface](ERROR): Altitude too low: -3.07 2017-06-06T20:57:14.696Z,1496782634.696 [Default:B.GoToSurface](ERROR): Altitude too low: -3.03 2017-06-06T20:57:15.097Z,1496782635.097 [Default:B.GoToSurface](ERROR): Altitude too low: -3.03 2017-06-06T20:57:15.500Z,1496782635.500 [Default:B.GoToSurface](ERROR): Altitude too low: -3.01 2017-06-06T20:57:15.910Z,1496782635.910 [Default:B.GoToSurface](ERROR): Altitude too low: -2.99 2017-06-06T20:57:16.309Z,1496782636.309 [Default:B.GoToSurface](ERROR): Altitude too low: -2.99 2017-06-06T20:57:16.693Z,1496782636.693 [Default:B.GoToSurface](ERROR): Altitude too low: -2.97 2017-06-06T20:57:17.112Z,1496782637.112 [Default:B.GoToSurface](ERROR): Altitude too low: -2.95 2017-06-06T20:57:17.512Z,1496782637.512 [Default:B.GoToSurface](ERROR): Altitude too low: -2.95 2017-06-06T20:57:17.891Z,1496782637.891 [Default:B.GoToSurface](ERROR): Altitude too low: -2.94 2017-06-06T20:57:18.290Z,1496782638.290 [Default:B.GoToSurface](ERROR): Altitude too low: -2.93 2017-06-06T20:57:18.696Z,1496782638.696 [Default:B.GoToSurface](ERROR): Altitude too low: -2.91 2017-06-06T20:57:19.099Z,1496782639.099 [Default:B.GoToSurface](ERROR): Altitude too low: -2.91 2017-06-06T20:57:19.499Z,1496782639.499 [Default:B.GoToSurface](ERROR): Altitude too low: -2.89 2017-06-06T20:57:19.905Z,1496782639.905 [Default:B.GoToSurface](ERROR): Altitude too low: -2.87 2017-06-06T20:57:20.306Z,1496782640.306 [Default:B.GoToSurface](ERROR): Altitude too low: -2.88 2017-06-06T20:57:20.703Z,1496782640.703 [Default:B.GoToSurface](ERROR): Altitude too low: -2.86 2017-06-06T20:57:21.112Z,1496782641.112 [Default:B.GoToSurface](ERROR): Altitude too low: -2.86 2017-06-06T20:57:21.510Z,1496782641.510 [Default:B.GoToSurface](ERROR): Altitude too low: -2.84 2017-06-06T20:57:21.922Z,1496782641.922 [Default:B.GoToSurface](ERROR): Altitude too low: -2.84 2017-06-06T20:57:22.310Z,1496782642.310 [Default:B.GoToSurface](ERROR): Altitude too low: -2.84 2017-06-06T20:57:22.394Z,1496782642.394 [CBIT](INFO): Clearing failed state for component CANONSampler 2017-06-06T20:57:22.394Z,1496782642.394 [CANONSampler] No Fault, FailCount= 1 2017-06-06T20:57:22.650Z,1496782642.650 [CANONSampler](INFO): Powering up 2017-06-06T20:57:22.738Z,1496782642.738 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:23.155Z,1496782643.155 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:23.507Z,1496782643.507 [Default:B.GoToSurface](ERROR): Altitude too low: -2.84 2017-06-06T20:57:23.839Z,1496782643.839 [CANONSampler](INFO): Powering down 2017-06-06T20:57:24.036Z,1496782644.036 [Default:B.GoToSurface](ERROR): Altitude too low: -2.81 2017-06-06T20:57:24.310Z,1496782644.310 [Default:B.GoToSurface](ERROR): Altitude too low: -2.80 2017-06-06T20:57:24.720Z,1496782644.720 [Default:B.GoToSurface](ERROR): Altitude too low: -2.80 2017-06-06T20:57:25.129Z,1496782645.129 [Default:B.GoToSurface](ERROR): Altitude too low: -2.80 2017-06-06T20:57:25.523Z,1496782645.523 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:25.524Z,1496782645.524 [Default:A.Wait](INFO): Done Waiting. 2017-06-06T20:57:25.524Z,1496782645.524 [Default:A.Wait] Stopped 2017-06-06T20:57:25.524Z,1496782645.524 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2017-06-06T20:57:25.934Z,1496782645.934 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:26.339Z,1496782646.339 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:26.699Z,1496782646.699 [Default:B.GoToSurface](ERROR): Altitude too low: -2.80 2017-06-06T20:57:27.106Z,1496782647.106 [Default:B.GoToSurface](ERROR): Altitude too low: -2.81 2017-06-06T20:57:27.498Z,1496782647.498 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:27.909Z,1496782647.909 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:28.311Z,1496782648.311 [Default:B.GoToSurface](ERROR): Altitude too low: -2.81 2017-06-06T20:57:28.701Z,1496782648.701 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:29.115Z,1496782649.115 [Default:B.GoToSurface](ERROR): Altitude too low: -2.83 2017-06-06T20:57:29.491Z,1496782649.491 [Default:B.GoToSurface](ERROR): Altitude too low: -2.83 2017-06-06T20:57:29.894Z,1496782649.894 [Default:B.GoToSurface](ERROR): Altitude too low: -2.83 2017-06-06T20:57:30.300Z,1496782650.300 [Default:B.GoToSurface](ERROR): Altitude too low: -2.85 2017-06-06T20:57:30.703Z,1496782650.703 [Default:B.GoToSurface](ERROR): Altitude too low: -2.85 2017-06-06T20:57:31.100Z,1496782651.100 [Default:B.GoToSurface](ERROR): Altitude too low: -2.85 2017-06-06T20:57:31.515Z,1496782651.515 [Default:B.GoToSurface](ERROR): Altitude too low: -2.85 2017-06-06T20:57:31.902Z,1496782651.902 [Default:B.GoToSurface](ERROR): Altitude too low: -2.84 2017-06-06T20:57:32.311Z,1496782652.311 [Default:B.GoToSurface](ERROR): Altitude too low: -2.85 2017-06-06T20:57:32.713Z,1496782652.713 [Default:B.GoToSurface](ERROR): Altitude too low: -2.85 2017-06-06T20:57:33.112Z,1496782653.112 [Default:B.GoToSurface](ERROR): Altitude too low: -2.86 2017-06-06T20:57:33.491Z,1496782653.491 [Default:B.GoToSurface](ERROR): Altitude too low: -2.87 2017-06-06T20:57:33.891Z,1496782653.891 [Default:B.GoToSurface](ERROR): Altitude too low: -2.87 2017-06-06T20:57:34.298Z,1496782654.298 [Default:B.GoToSurface](ERROR): Altitude too low: -2.87 2017-06-06T20:57:34.700Z,1496782654.700 [Default:B.GoToSurface](ERROR): Altitude too low: -2.88 2017-06-06T20:57:35.046Z,1496782655.046 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:57:35.128Z,1496782655.128 [Default:B.GoToSurface](ERROR): Altitude too low: -2.87 2017-06-06T20:57:35.506Z,1496782655.506 [Default:B.GoToSurface](ERROR): Altitude too low: -2.88 2017-06-06T20:57:35.900Z,1496782655.900 [Default:B.GoToSurface](ERROR): Altitude too low: -2.88 2017-06-06T20:57:36.304Z,1496782656.304 [Default:B.GoToSurface](ERROR): Altitude too low: -2.89 2017-06-06T20:57:36.709Z,1496782656.709 [Default:B.GoToSurface](ERROR): Altitude too low: -2.89 2017-06-06T20:57:37.121Z,1496782657.121 [Default:B.GoToSurface](ERROR): Altitude too low: -2.88 2017-06-06T20:57:37.515Z,1496782657.515 [Default:B.GoToSurface](ERROR): Altitude too low: -2.90 2017-06-06T20:57:37.922Z,1496782657.922 [Default:B.GoToSurface](ERROR): Altitude too low: -2.89 2017-06-06T20:57:38.310Z,1496782658.310 [Default:B.GoToSurface](ERROR): Altitude too low: -2.88 2017-06-06T20:57:38.771Z,1496782658.771 [Default:B.GoToSurface](ERROR): Altitude too low: -2.90 2017-06-06T20:57:39.099Z,1496782659.099 [Default:B.GoToSurface](ERROR): Altitude too low: -2.89 2017-06-06T20:57:39.535Z,1496782659.535 [Default:B.GoToSurface](ERROR): Altitude too low: -2.89 2017-06-06T20:57:39.899Z,1496782659.899 [Default:B.GoToSurface](ERROR): Altitude too low: -2.90 2017-06-06T20:57:40.331Z,1496782660.331 [Default:B.GoToSurface](ERROR): Altitude too low: -2.90 2017-06-06T20:57:40.738Z,1496782660.738 [Default:B.GoToSurface](ERROR): Altitude too low: -2.90 2017-06-06T20:57:41.098Z,1496782661.098 [Default:B.GoToSurface](ERROR): Altitude too low: -2.89 2017-06-06T20:57:41.509Z,1496782661.509 [Default:B.GoToSurface](ERROR): Altitude too low: -2.88 2017-06-06T20:57:41.901Z,1496782661.901 [Default:B.GoToSurface](ERROR): Altitude too low: -2.88 2017-06-06T20:57:42.306Z,1496782662.306 [Default:B.GoToSurface](ERROR): Altitude too low: -2.86 2017-06-06T20:57:42.713Z,1496782662.713 [Default:B.GoToSurface](ERROR): Altitude too low: -2.86 2017-06-06T20:57:43.116Z,1496782663.116 [Default:B.GoToSurface](ERROR): Altitude too low: -2.86 2017-06-06T20:57:43.518Z,1496782663.518 [Default:B.GoToSurface](ERROR): Altitude too low: -2.84 2017-06-06T20:57:43.887Z,1496782663.887 [Default:B.GoToSurface](ERROR): Altitude too low: -2.85 2017-06-06T20:57:44.291Z,1496782664.291 [Default:B.GoToSurface](ERROR): Altitude too low: -2.85 2017-06-06T20:57:44.691Z,1496782664.691 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:45.095Z,1496782665.095 [Default:B.GoToSurface](ERROR): Altitude too low: -2.82 2017-06-06T20:57:45.500Z,1496782665.500 [Default:B.GoToSurface](ERROR): Altitude too low: -2.81 2017-06-06T20:57:45.906Z,1496782665.906 [Default:B.GoToSurface](ERROR): Altitude too low: -2.80 2017-06-06T20:57:46.294Z,1496782666.294 [Default:B.GoToSurface](ERROR): Altitude too low: -2.80 2017-06-06T20:57:46.697Z,1496782666.697 [Default:B.GoToSurface](ERROR): Altitude too low: -2.77 2017-06-06T20:57:47.104Z,1496782667.104 [Default:B.GoToSurface](ERROR): Altitude too low: -2.76 2017-06-06T20:57:47.503Z,1496782667.503 [Default:B.GoToSurface](ERROR): Altitude too low: -2.74 2017-06-06T20:57:47.926Z,1496782667.926 [Default:B.GoToSurface](ERROR): Altitude too low: -2.74 2017-06-06T20:57:48.314Z,1496782668.314 [Default:B.GoToSurface](ERROR): Altitude too low: -2.71 2017-06-06T20:57:48.699Z,1496782668.699 [Default:B.GoToSurface](ERROR): Altitude too low: -2.69 2017-06-06T20:57:49.097Z,1496782669.097 [Default:B.GoToSurface](ERROR): Altitude too low: -2.67 2017-06-06T20:57:49.497Z,1496782669.497 [Default:B.GoToSurface](ERROR): Altitude too low: -2.65 2017-06-06T20:57:49.897Z,1496782669.897 [Default:B.GoToSurface](ERROR): Altitude too low: -2.64 2017-06-06T20:57:50.310Z,1496782670.310 [Default:B.GoToSurface](ERROR): Altitude too low: -2.61 2017-06-06T20:57:50.702Z,1496782670.702 [Default:B.GoToSurface](ERROR): Altitude too low: -2.61 2017-06-06T20:57:51.108Z,1496782671.108 [Default:B.GoToSurface](ERROR): Altitude too low: -2.60 2017-06-06T20:57:51.520Z,1496782671.520 [Default:B.GoToSurface](ERROR): Altitude too low: -2.59 2017-06-06T20:57:51.912Z,1496782671.912 [Default:B.GoToSurface](ERROR): Altitude too low: -2.54 2017-06-06T20:57:52.318Z,1496782672.318 [Default:B.GoToSurface](ERROR): Altitude too low: -2.53 2017-06-06T20:57:52.753Z,1496782672.753 [Default:B.GoToSurface](ERROR): Altitude too low: -2.52 2017-06-06T20:57:53.261Z,1496782673.261 [Default:B.GoToSurface](ERROR): Altitude too low: -2.49 2017-06-06T20:57:53.671Z,1496782673.671 [Default:B.GoToSurface](ERROR): Altitude too low: -2.47 2017-06-06T20:57:54.075Z,1496782674.075 [Default:B.GoToSurface](ERROR): Altitude too low: -2.45 2017-06-06T20:57:54.461Z,1496782674.461 [Default:B.GoToSurface](ERROR): Altitude too low: -2.42 2017-06-06T20:57:54.896Z,1496782674.896 [Default:B.GoToSurface](ERROR): Altitude too low: -2.42 2017-06-06T20:57:55.289Z,1496782675.289 [Default:B.GoToSurface](ERROR): Altitude too low: -2.40 2017-06-06T20:57:55.660Z,1496782675.660 [Default:B.GoToSurface](ERROR): Altitude too low: -2.37 2017-06-06T20:57:56.052Z,1496782676.052 [Default:B.GoToSurface](ERROR): Altitude too low: -2.36 2017-06-06T20:57:56.453Z,1496782676.453 [Default:B.GoToSurface](ERROR): Altitude too low: -2.33 2017-06-06T20:57:56.865Z,1496782676.865 [Default:B.GoToSurface](ERROR): Altitude too low: -2.33 2017-06-06T20:57:57.258Z,1496782677.258 [Default:B.GoToSurface](ERROR): Altitude too low: -2.31 2017-06-06T20:57:57.670Z,1496782677.670 [Default:B.GoToSurface](ERROR): Altitude too low: -2.29 2017-06-06T20:57:58.076Z,1496782678.076 [Default:B.GoToSurface](ERROR): Altitude too low: -2.28 2017-06-06T20:57:58.460Z,1496782678.460 [Default:B.GoToSurface](ERROR): Altitude too low: -2.26 2017-06-06T20:57:58.860Z,1496782678.860 [Default:B.GoToSurface](ERROR): Altitude too low: -2.23 2017-06-06T20:57:59.278Z,1496782679.278 [Default:B.GoToSurface](ERROR): Altitude too low: -2.22 2017-06-06T20:57:59.655Z,1496782679.655 [Default:B.GoToSurface](ERROR): Altitude too low: -2.21 2017-06-06T20:58:00.076Z,1496782680.076 [Default:B.GoToSurface](ERROR): Altitude too low: -2.20 2017-06-06T20:58:00.475Z,1496782680.475 [Default:B.GoToSurface](ERROR): Altitude too low: -2.17 2017-06-06T20:58:00.842Z,1496782680.842 [Default:B.GoToSurface](ERROR): Altitude too low: -2.16 2017-06-06T20:58:01.269Z,1496782681.269 [Default:B.GoToSurface](ERROR): Altitude too low: -2.15 2017-06-06T20:58:01.655Z,1496782681.655 [Default:B.GoToSurface](ERROR): Altitude too low: -2.13 2017-06-06T20:58:02.052Z,1496782682.052 [Default:B.GoToSurface](ERROR): Altitude too low: -2.10 2017-06-06T20:58:02.466Z,1496782682.466 [Default:B.GoToSurface](ERROR): Altitude too low: -2.09 2017-06-06T20:58:02.868Z,1496782682.868 [Default:B.GoToSurface](ERROR): Altitude too low: -2.08 2017-06-06T20:58:03.256Z,1496782683.256 [Default:B.GoToSurface](ERROR): Altitude too low: -2.06 2017-06-06T20:58:03.671Z,1496782683.671 [Default:B.GoToSurface](ERROR): Altitude too low: -2.04 2017-06-06T20:58:04.071Z,1496782684.071 [Default:B.GoToSurface](ERROR): Altitude too low: -2.03 2017-06-06T20:58:04.460Z,1496782684.460 [Default:B.GoToSurface](ERROR): Altitude too low: -2.01 2017-06-06T20:58:04.853Z,1496782684.853 [Default:B.GoToSurface](ERROR): Altitude too low: -2.00 2017-06-06T20:58:05.248Z,1496782685.248 [Default:B.GoToSurface](ERROR): Altitude too low: -1.99 2017-06-06T20:58:05.651Z,1496782685.651 [Default:B.GoToSurface](ERROR): Altitude too low: -1.97 2017-06-06T20:58:06.118Z,1496782686.118 [Default:B.GoToSurface](ERROR): Altitude too low: -1.94 2017-06-06T20:58:06.456Z,1496782686.456 [Default:B.GoToSurface](ERROR): Altitude too low: -1.92 2017-06-06T20:58:06.868Z,1496782686.868 [Default:B.GoToSurface](ERROR): Altitude too low: -1.91 2017-06-06T20:58:07.273Z,1496782687.273 [Default:B.GoToSurface](ERROR): Altitude too low: -1.89 2017-06-06T20:58:07.682Z,1496782687.682 [Default:B.GoToSurface](ERROR): Altitude too low: -1.88 2017-06-06T20:58:08.049Z,1496782688.049 [Default:B.GoToSurface](ERROR): Altitude too low: -1.86 2017-06-06T20:58:08.493Z,1496782688.493 [Default:B.GoToSurface](ERROR): Altitude too low: -1.85 2017-06-06T20:58:09.007Z,1496782689.007 [Default:B.GoToSurface](ERROR): Altitude too low: -1.81 2017-06-06T20:58:09.441Z,1496782689.441 [Default:B.GoToSurface](ERROR): Altitude too low: -1.79 2017-06-06T20:58:09.847Z,1496782689.847 [Default:B.GoToSurface](ERROR): Altitude too low: -1.76 2017-06-06T20:58:10.208Z,1496782690.208 [Default:B.GoToSurface](ERROR): Altitude too low: -1.74 2017-06-06T20:58:10.606Z,1496782690.606 [Default:B.GoToSurface](ERROR): Altitude too low: -1.72 2017-06-06T20:58:11.070Z,1496782691.070 [Default:B.GoToSurface](ERROR): Altitude too low: -1.70 2017-06-06T20:58:11.410Z,1496782691.410 [Default:B.GoToSurface](ERROR): Altitude too low: -1.69 2017-06-06T20:58:11.816Z,1496782691.816 [Default:B.GoToSurface](ERROR): Altitude too low: -1.66 2017-06-06T20:58:12.201Z,1496782692.201 [Default:B.GoToSurface](ERROR): Altitude too low: -1.62 2017-06-06T20:58:12.618Z,1496782692.618 [Default:B.GoToSurface](ERROR): Altitude too low: -1.60 2017-06-06T20:58:12.998Z,1496782692.998 [Default:B.GoToSurface](ERROR): Altitude too low: -1.57 2017-06-06T20:58:13.416Z,1496782693.416 [Default:B.GoToSurface](ERROR): Altitude too low: -1.55 2017-06-06T20:58:13.820Z,1496782693.820 [Default:B.GoToSurface](ERROR): Altitude too low: -1.51 2017-06-06T20:58:14.215Z,1496782694.215 [Default:B.GoToSurface](ERROR): Altitude too low: -1.49 2017-06-06T20:58:14.625Z,1496782694.625 [Default:B.GoToSurface](ERROR): Altitude too low: -1.46 2017-06-06T20:58:15.038Z,1496782695.038 [Default:B.GoToSurface](ERROR): Altitude too low: -1.43 2017-06-06T20:58:15.393Z,1496782695.393 [Default:B.GoToSurface](ERROR): Altitude too low: -1.41 2017-06-06T20:58:15.816Z,1496782695.816 [Default:B.GoToSurface](ERROR): Altitude too low: -1.38 2017-06-06T20:58:16.199Z,1496782696.199 [Default:B.GoToSurface](ERROR): Altitude too low: -1.36 2017-06-06T20:58:16.662Z,1496782696.662 [Default:B.GoToSurface](ERROR): Altitude too low: -1.30 2017-06-06T20:58:17.005Z,1496782697.005 [Default:B.GoToSurface](ERROR): Altitude too low: -1.30 2017-06-06T20:58:17.406Z,1496782697.406 [Default:B.GoToSurface](ERROR): Altitude too low: -1.26 2017-06-06T20:58:17.817Z,1496782697.817 [Default:B.GoToSurface](ERROR): Altitude too low: -1.22 2017-06-06T20:58:18.216Z,1496782698.216 [Default:B.GoToSurface](ERROR): Altitude too low: -1.20 2017-06-06T20:58:18.615Z,1496782698.615 [Default:B.GoToSurface](ERROR): Altitude too low: -1.17 2017-06-06T20:58:18.996Z,1496782698.996 [Default:B.GoToSurface](ERROR): Altitude too low: -1.14 2017-06-06T20:58:19.397Z,1496782699.397 [Default:B.GoToSurface](ERROR): Altitude too low: -1.12 2017-06-06T20:58:19.814Z,1496782699.814 [Default:B.GoToSurface](ERROR): Altitude too low: -1.09 2017-06-06T20:58:20.207Z,1496782700.207 [Default:B.GoToSurface](ERROR): Altitude too low: -1.06 2017-06-06T20:58:20.604Z,1496782700.604 [Default:B.GoToSurface](ERROR): Altitude too low: -1.03 2017-06-06T20:58:21.040Z,1496782701.040 [Default:B.GoToSurface](ERROR): Altitude too low: -0.99 2017-06-06T20:58:21.404Z,1496782701.404 [Default:B.GoToSurface](ERROR): Altitude too low: -0.97 2017-06-06T20:58:21.839Z,1496782701.839 [Default:B.GoToSurface](ERROR): Altitude too low: -0.94 2017-06-06T20:58:22.193Z,1496782702.193 [Default:B.GoToSurface](ERROR): Altitude too low: -0.91 2017-06-06T20:58:22.629Z,1496782702.629 [Default:B.GoToSurface](ERROR): Altitude too low: -0.89 2017-06-06T20:58:23.003Z,1496782703.003 [Default:B.GoToSurface](ERROR): Altitude too low: -0.86 2017-06-06T20:58:23.430Z,1496782703.430 [Default:B.GoToSurface](ERROR): Altitude too low: -0.82 2017-06-06T20:58:23.805Z,1496782703.805 [Default:B.GoToSurface](ERROR): Altitude too low: -0.82 2017-06-06T20:58:24.205Z,1496782704.205 [Default:B.GoToSurface](ERROR): Altitude too low: -0.78 2017-06-06T20:58:24.610Z,1496782704.610 [Default:B.GoToSurface](ERROR): Altitude too low: -0.76 2017-06-06T20:58:25.017Z,1496782705.017 [Default:B.GoToSurface](ERROR): Altitude too low: -0.72 2017-06-06T20:58:25.418Z,1496782705.418 [Default:B.GoToSurface](ERROR): Altitude too low: -0.69 2017-06-06T20:58:25.788Z,1496782705.788 [Default:B.GoToSurface](ERROR): Altitude too low: -0.66 2017-06-06T20:58:26.189Z,1496782706.189 [Default:B.GoToSurface](ERROR): Altitude too low: -0.63 2017-06-06T20:58:26.619Z,1496782706.619 [Default:B.GoToSurface](ERROR): Altitude too low: -0.60 2017-06-06T20:58:26.991Z,1496782706.991 [Default:B.GoToSurface](ERROR): Altitude too low: -0.58 2017-06-06T20:58:27.396Z,1496782707.396 [Default:B.GoToSurface](ERROR): Altitude too low: -0.54 2017-06-06T20:58:27.846Z,1496782707.846 [Default:B.GoToSurface](ERROR): Altitude too low: -0.52 2017-06-06T20:58:28.215Z,1496782708.215 [Default:B.GoToSurface](ERROR): Altitude too low: -0.49 2017-06-06T20:58:28.590Z,1496782708.590 [Default:B.GoToSurface](ERROR): Altitude too low: -0.44 2017-06-06T20:58:29.019Z,1496782709.019 [Default:B.GoToSurface](ERROR): Altitude too low: -0.42 2017-06-06T20:58:29.390Z,1496782709.390 [Default:B.GoToSurface](ERROR): Altitude too low: -0.40 2017-06-06T20:58:29.796Z,1496782709.796 [Default:B.GoToSurface](ERROR): Altitude too low: -0.38 2017-06-06T20:58:30.208Z,1496782710.208 [Default:B.GoToSurface](ERROR): Altitude too low: -0.33 2017-06-06T20:58:30.602Z,1496782710.602 [Default:B.GoToSurface](ERROR): Altitude too low: -0.32 2017-06-06T20:58:31.012Z,1496782711.012 [Default:B.GoToSurface](ERROR): Altitude too low: -0.29 2017-06-06T20:58:31.405Z,1496782711.405 [Default:B.GoToSurface](ERROR): Altitude too low: -0.24 2017-06-06T20:58:31.840Z,1496782711.840 [Default:B.GoToSurface](ERROR): Altitude too low: -0.22 2017-06-06T20:58:31.894Z,1496782711.894 [CBIT](DEBUG): EFC running - data check-sum false 2017-06-06T20:58:32.192Z,1496782712.192 [Default:B.GoToSurface](ERROR): Altitude too low: -0.18 2017-06-06T20:58:32.599Z,1496782712.599 [Default:B.GoToSurface](ERROR): Altitude too low: -0.16 2017-06-06T20:58:33.028Z,1496782713.028 [Default:B.GoToSurface](ERROR): Altitude too low: -0.12 2017-06-06T20:58:33.443Z,1496782713.443 [Default:B.GoToSurface](ERROR): Altitude too low: -0.09 2017-06-06T20:58:33.807Z,1496782713.807 [Default:B.GoToSurface](ERROR): Altitude too low: -0.06 2017-06-06T20:58:34.193Z,1496782714.193 [Default:B.GoToSurface](ERROR): Altitude too low: -0.04 2017-06-06T20:58:34.602Z,1496782714.602 [Default:B.GoToSurface](ERROR): Altitude too low: -0.02 2017-06-06T20:58:35.045Z,1496782715.045 [Default:B.GoToSurface](ERROR): Altitude too low: 0.02 2017-06-06T20:58:35.345Z,1496782715.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:58:35.421Z,1496782715.421 [Default:B.GoToSurface](ERROR): Altitude too low: 0.04 2017-06-06T20:58:35.830Z,1496782715.830 [Default:B.GoToSurface](ERROR): Altitude too low: 0.08 2017-06-06T20:58:36.191Z,1496782716.191 [Default:B.GoToSurface](ERROR): Altitude too low: 0.11 2017-06-06T20:58:36.627Z,1496782716.627 [Default:B.GoToSurface](ERROR): Altitude too low: 0.13 2017-06-06T20:58:37.000Z,1496782717.000 [Default:B.GoToSurface](ERROR): Altitude too low: 0.18 2017-06-06T20:58:37.439Z,1496782717.439 [Default:B.GoToSurface](ERROR): Altitude too low: 0.20 2017-06-06T20:58:37.831Z,1496782717.831 [Default:B.GoToSurface](ERROR): Altitude too low: 0.24 2017-06-06T20:58:38.204Z,1496782718.204 [Default:B.GoToSurface](ERROR): Altitude too low: 0.25 2017-06-06T20:58:38.606Z,1496782718.606 [Default:B.GoToSurface](ERROR): Altitude too low: 0.29 2017-06-06T20:58:39.011Z,1496782719.011 [Default:B.GoToSurface](ERROR): Altitude too low: 0.31 2017-06-06T20:58:39.409Z,1496782719.409 [Default:B.GoToSurface](ERROR): Altitude too low: 0.36 2017-06-06T20:58:39.793Z,1496782719.793 [Default:B.GoToSurface](ERROR): Altitude too low: 0.40 2017-06-06T20:58:40.302Z,1496782720.302 [Default:B.GoToSurface](ERROR): Altitude too low: 0.39 2017-06-06T20:58:40.695Z,1496782720.695 [Default:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -5.02 2017-06-06T20:58:48.423Z,1496782728.423 [Default:CheckIn] Running Loop=1 2017-06-06T20:58:48.424Z,1496782728.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-06-06T20:58:48.424Z,1496782728.424 [Default:CheckIn:Read_GPS] Running Loop=1 2017-06-06T20:58:49.179Z,1496782729.179 [NAL9602](INFO): Powering up 2017-06-06T20:59:00.123Z,1496782740.123 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:59:00.922Z,1496782740.922 [NAL9602](FAULT): GPS failed to acquire within timeout. 2017-06-06T20:59:00.922Z,1496782740.922 [NAL9602] Data Fault, FailCount= 3 2017-06-06T20:59:00.922Z,1496782740.922 [NAL9602](ERROR): Data Fault 2017-06-06T20:59:01.082Z,1496782741.082 [CBIT](ERROR): Data Fault in component: NAL9602 2017-06-06T20:59:01.324Z,1496782741.324 [NAL9602](INFO): Powering down 2017-06-06T20:59:02.279Z,1496782742.279 [CBIT](INFO): Clearing failed state for component NAL9602 2017-06-06T20:59:02.280Z,1496782742.280 [NAL9602] No Fault, FailCount= 3 2017-06-06T20:59:06.523Z,1496782746.523 [NAL9602](INFO): Powering up NAL9602 2017-06-06T20:59:17.368Z,1496782757.368 [NAL9602](INFO): NAL9602 initialized 2017-06-06T20:59:35.721Z,1496782775.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T20:59:46.979Z,1496782786.979 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T20:59:46.979Z,1496782786.979 [BPC1] Data Fault, FailCount= 1 2017-06-06T20:59:46.979Z,1496782786.979 [BPC1](ERROR): Data Fault 2017-06-06T20:59:47.055Z,1496782787.055 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T20:59:48.209Z,1496782788.209 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T20:59:48.209Z,1496782788.209 [BPC1] No Fault, FailCount= 1 2017-06-06T21:00:35.720Z,1496782835.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T21:01:16.168Z,1496782876.168 [BPC1](ERROR): Failed to receive battery data 2017-06-06T21:01:16.168Z,1496782876.168 [BPC1] Communications Fault, FailCount= 1 2017-06-06T21:01:16.168Z,1496782876.168 [BPC1](ERROR): Communications Fault 2017-06-06T21:01:16.237Z,1496782876.237 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T21:01:17.398Z,1496782877.398 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T21:01:17.398Z,1496782877.398 [BPC1] No Fault, FailCount= 1 2017-06-06T21:01:36.127Z,1496782896.127 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T21:02:36.523Z,1496782956.523 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T21:03:36.918Z,1496783016.918 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T21:03:48.584Z,1496783028.584 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-06-06T20:58:48.4Z 2017-06-06T21:03:48.584Z,1496783028.584 [Default:CheckIn:Read_GPS] Stopped 2017-06-06T21:03:48.584Z,1496783028.584 [Default:CheckIn:Read_Iridium] Running Loop=1 2017-06-06T21:04:21.323Z,1496783061.323 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2017-06-06T21:04:36.921Z,1496783076.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2017-06-06T21:05:14.428Z,1496783114.428 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002606 2017-06-06T21:05:18.658Z,1496783118.658 [DataOverHttps](INFO): Sending 46 bytes from file Logs/20170606T200529/Courier0016.lzma 2017-06-06T21:05:19.389Z,1496783119.389 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Courier0016.lzma.bak 2017-06-06T21:05:19.390Z,1496783119.390 [DataOverHttps](INFO): SBD MOMSN=5045978 2017-06-06T21:05:20.208Z,1496783120.208 [NAL9602](INFO): Powering down 2017-06-06T21:05:30.647Z,1496783130.647 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20170606T200529/Courier0020.lzma 2017-06-06T21:05:31.477Z,1496783131.477 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Courier0020.lzma.bak 2017-06-06T21:05:31.477Z,1496783131.477 [DataOverHttps](INFO): SBD MOMSN=5045980 2017-06-06T21:05:35.783Z,1496783135.783 [CommandLine](IMPORTANT): got command show variable canon 2017-06-06T21:05:35.822Z,1496783135.822 [CommandLine](IMPORTANT): CANONSampler.loadAtStartup (bool) 2017-06-06T21:05:35.822Z,1496783135.822 [CommandLine](IMPORTANT): CANONSampler.simulateHardware (bool) 2017-06-06T21:05:35.822Z,1496783135.822 [CommandLine](IMPORTANT): CANONSampler.sampleTimeout (minute) 2017-06-06T21:05:35.926Z,1496783135.926 [CommandLine](IMPORTANT): CANONSampler.loadControl (none) 2017-06-06T21:05:35.926Z,1496783135.926 [CommandLine](IMPORTANT): CANONSampler.uart (none) 2017-06-06T21:05:35.927Z,1496783135.927 [CommandLine](IMPORTANT): CANONSampler.baud (bit_per_second) 2017-06-06T21:05:36.002Z,1496783136.002 [CommandLine](IMPORTANT): CANONSampler.sampling (bool) 2017-06-06T21:05:36.002Z,1496783136.002 [CommandLine](IMPORTANT): CANONSampler.sample_number (count) 2017-06-06T21:05:36.069Z,1496783136.069 [CommandLine](IMPORTANT): CANONSampler.component_voltage (volt) 2017-06-06T21:05:36.069Z,1496783136.069 [CommandLine](IMPORTANT): CANONSampler.component_avgVoltage (volt) 2017-06-06T21:05:36.090Z,1496783136.090 [CommandLine](IMPORTANT): CANONSampler.component_current (milliampere) 2017-06-06T21:05:36.090Z,1496783136.090 [CommandLine](IMPORTANT): CANONSampler.component_avgCurrent (milliampere) 2017-06-06T21:05:36.091Z,1496783136.091 [CommandLine](IMPORTANT): CANONSampler.durationOfLastRun (second) 2017-06-06T21:05:40.860Z,1496783140.860 [DataOverHttps](INFO): Sending 1179 bytes from file Logs/20170606T200529/Express0017.lzma 2017-06-06T21:05:41.624Z,1496783141.624 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Express0017.lzma.bak 2017-06-06T21:05:41.625Z,1496783141.625 [DataOverHttps](INFO): SBD MOMSN=5045982 2017-06-06T21:05:47.426Z,1496783147.426 [CommandLine](IMPORTANT): got command report touch CANONSampler.component_current 2017-06-06T21:05:51.354Z,1496783151.354 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20170606T200529/Express0021.lzma 2017-06-06T21:05:51.403Z,1496783151.403 [BPC1](ERROR): Failed to receive battery data 2017-06-06T21:05:51.403Z,1496783151.403 [BPC1] Communications Fault, FailCount= 1 2017-06-06T21:05:51.403Z,1496783151.403 [BPC1](ERROR): Communications Fault 2017-06-06T21:05:51.477Z,1496783151.477 [CBIT](ERROR): Communications Fault in component: BPC1 2017-06-06T21:05:52.105Z,1496783152.105 [DataOverHttps](INFO): Moved sent file to Logs/20170606T200529/Express0021.lzma.bak 2017-06-06T21:05:52.105Z,1496783152.105 [DataOverHttps](INFO): SBD MOMSN=5046070 2017-06-06T21:05:53.714Z,1496783153.714 [Default:CheckIn:Read_Iridium] Stopped 2017-06-06T21:05:53.715Z,1496783153.715 [Default:CheckIn:C.Wait] Running Loop=1 2017-06-06T21:05:53.715Z,1496783153.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2017-06-06T21:05:53.746Z,1496783153.746 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T21:05:53.746Z,1496783153.746 [BPC1] No Fault, FailCount= 1 2017-06-06T21:05:56.376Z,1496783156.376 [CommandLine](IMPORTANT): got command report touch CANONSampler.component_avgCurrent 2017-06-06T21:05:58.931Z,1496783158.931 [PNI_TCM](ERROR): CRC does not match. Expected:0x62598 got:0x1857 2017-06-06T21:06:04.055Z,1496783164.055 [CommandLine](IMPORTANT): got command report touch CANONSampler.component_voltage 2017-06-06T21:06:06.537Z,1496783166.537 [PNI_TCM](ERROR): CRC does not match. Expected:0x20646 got:0x7105 2017-06-06T21:06:12.477Z,1496783172.477 [CommandLine](IMPORTANT): got command report touch CANONSampler.component_avgVoltage 2017-06-06T21:06:16.327Z,1496783176.327 [CommandLine](IMPORTANT): got command show stack 2017-06-06T21:06:16.327Z,1496783176.327 [CommandLine](IMPORTANT): Behavior Stack: 2017-06-06T21:06:16.328Z,1496783176.328 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2017-06-06T21:06:16.328Z,1496783176.328 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:C.Wait 2017-06-06T21:06:20.934Z,1496783180.934 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501 2017-06-06T21:06:35.726Z,1496783195.726 [PNI_TCM](ERROR): CRC does not match. Expected:0x56300 got:0x40658 2017-06-06T21:06:42.524Z,1496783202.524 [PNI_TCM](ERROR): CRC does not match. Expected:0x62527 got:0x22791 2017-06-06T21:06:50.517Z,1496783210.517 [PNI_TCM](ERROR): CRC does not match. Expected:0x47839 got:0x6337 2017-06-06T21:07:23.118Z,1496783243.118 [BPC1](ERROR): Failed to parse bank A battery data 2017-06-06T21:07:23.118Z,1496783243.118 [BPC1] Data Fault, FailCount= 1 2017-06-06T21:07:23.118Z,1496783243.118 [BPC1](ERROR): Data Fault 2017-06-06T21:07:23.196Z,1496783243.196 [CBIT](ERROR): Data Fault in component: BPC1 2017-06-06T21:07:24.360Z,1496783244.360 [CBIT](INFO): Clearing failed state for component BPC1 2017-06-06T21:07:24.360Z,1496783244.360 [BPC1] No Fault, FailCount= 1 2017-06-06T21:07:58.577Z,1496783278.577 [DataOverHttps](IMPORTANT): SBD MTMSN=20170606T210720