2019-07-19T03:14:20.427Z,1563506060.427 [CommandLine](IMPORTANT): got command restart logs
2019-07-19T03:14:46.920Z,1563506086.920 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:14:46.920Z,1563506086.920 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36
2019-07-19T03:14:46.920Z,1563506086.920 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:14:46.931Z,1563506086.931 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:14:47.333Z,1563506087.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:14:47.334Z,1563506087.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36
2019-07-19T03:15:13.291Z,1563506113.291 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.797001 degree
2019-07-19T03:15:13.292Z,1563506113.292 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2019-07-19T03:15:13.600Z,1563506113.600 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-19T03:15:37.727Z,1563506137.727 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.847000 degree
2019-07-19T03:15:37.728Z,1563506137.728 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2019-07-19T03:15:37.856Z,1563506137.856 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-19T03:16:13.372Z,1563506173.372 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T03:16:13.372Z,1563506173.372 [Default:CheckIn:C.Wait] Stopped
2019-07-19T03:16:13.372Z,1563506173.372 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T03:16:13.373Z,1563506173.373 [Default:CheckIn:D] Running Loop=1
2019-07-19T03:16:13.783Z,1563506173.783 [Default:CheckIn:D] Stopped
2019-07-19T03:16:13.783Z,1563506173.783 [Default:CheckIn:E] Running Loop=1
2019-07-19T03:16:14.185Z,1563506174.185 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.825732 min 
2019-07-19T03:16:14.185Z,1563506174.185 [Default:CheckIn:E] Stopped
2019-07-19T03:16:14.185Z,1563506174.185 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T03:16:14.186Z,1563506174.186 [Default:CheckIn] Stopped
2019-07-19T03:16:14.186Z,1563506174.186 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T03:16:14.186Z,1563506174.186 [Default:CheckIn](INFO): Running loop #5
2019-07-19T03:16:14.186Z,1563506174.186 [Default:CheckIn] Running Loop=5
2019-07-19T03:16:14.186Z,1563506174.186 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T03:16:14.186Z,1563506174.186 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T03:16:15.789Z,1563506175.789 [NAL9602](DEBUG): Fix Requested
2019-07-19T03:16:46.883Z,1563506206.883 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-07-19T03:16:46.961Z,1563506206.961 [NAL9602](FAULT): received: 
+CSQ:0

OK927, 2, 0, 0, 0

OK
2019-07-19T03:16:46.961Z,1563506206.961 [NAL9602] Data Fault, FailCount= 5
2019-07-19T03:16:46.961Z,1563506206.961 [NAL9602](ERROR): Data Fault
2019-07-19T03:16:47.003Z,1563506207.003 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T03:16:47.004Z,1563506207.004 [CBIT](CRITICAL): Data Fault in component: NAL9602
2019-07-19T03:16:47.296Z,1563506207.296 [NAL9602](INFO): Powering down
2019-07-19T03:16:47.328Z,1563506207.328 [CBIT](INFO): Critical error at 20190719T031647
2019-07-19T03:16:47.777Z,1563506207.777 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:16:47.777Z,1563506207.777 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 37
2019-07-19T03:16:47.777Z,1563506207.777 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:16:47.820Z,1563506207.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:16:48.135Z,1563506208.135 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:16:48.135Z,1563506208.135 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 37
2019-07-19T03:18:47.332Z,1563506327.332 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T03:18:47.332Z,1563506327.332 [NAL9602] No Fault, FailCount= 5
2019-07-19T03:18:47.690Z,1563506327.690 [NAL9602](INFO): Powering up NAL9602
2019-07-19T03:18:48.577Z,1563506328.577 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:18:48.577Z,1563506328.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 38
2019-07-19T03:18:48.577Z,1563506328.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:18:48.588Z,1563506328.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:18:48.945Z,1563506328.945 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:18:48.945Z,1563506328.945 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 38
2019-07-19T03:18:58.594Z,1563506338.594 [NAL9602](INFO): NAL9602 initialized
2019-07-19T03:18:59.402Z,1563506339.402 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-19T03:18:59.402Z,1563506339.402 [NAL9602] Data Fault, FailCount= 1
2019-07-19T03:18:59.402Z,1563506339.402 [NAL9602](ERROR): Data Fault
2019-07-19T03:18:59.599Z,1563506339.599 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T03:18:59.806Z,1563506339.806 [NAL9602](INFO): Powering down
2019-07-19T03:19:00.681Z,1563506340.681 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T03:19:00.681Z,1563506340.681 [NAL9602] No Fault, FailCount= 1
2019-07-19T03:19:30.106Z,1563506370.106 [NAL9602](INFO): Powering up NAL9602
2019-07-19T03:19:41.015Z,1563506381.015 [NAL9602](INFO): NAL9602 initialized
2019-07-19T03:19:41.837Z,1563506381.837 [NAL9602](DEBUG): Fix Requested
2019-07-19T03:20:49.714Z,1563506449.714 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:20:49.715Z,1563506449.715 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 39
2019-07-19T03:20:49.715Z,1563506449.715 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:20:49.725Z,1563506449.725 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:20:50.199Z,1563506450.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:20:50.199Z,1563506450.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 39
2019-07-19T03:21:14.384Z,1563506474.384 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T03:16:14.2Z
2019-07-19T03:21:14.384Z,1563506474.384 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T03:21:14.384Z,1563506474.384 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T03:21:17.974Z,1563506477.974 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T03:21:21.532Z,1563506481.532 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190719T015755/Courier0019.lzma
2019-07-19T03:21:22.338Z,1563506482.338 [DataOverHttps](INFO): Moved sent file to Logs/20190719T015755/Courier0019.lzma.bak
2019-07-19T03:21:22.339Z,1563506482.339 [DataOverHttps](INFO): SBD MOMSN=11455888
2019-07-19T03:21:37.180Z,1563506497.180 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20190719T031420/Courier0000.lzma
2019-07-19T03:21:37.986Z,1563506497.986 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Courier0000.lzma.bak
2019-07-19T03:21:37.986Z,1563506497.986 [DataOverHttps](INFO): SBD MOMSN=11455890
2019-07-19T03:21:48.677Z,1563506508.677 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-19T03:21:57.469Z,1563506517.469 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20190719T015755/Express0020.lzma
2019-07-19T03:21:58.274Z,1563506518.274 [DataOverHttps](INFO): Moved sent file to Logs/20190719T015755/Express0020.lzma.bak
2019-07-19T03:21:58.275Z,1563506518.275 [DataOverHttps](INFO): SBD MOMSN=11455894
2019-07-19T03:22:14.565Z,1563506534.565 [DataOverHttps](INFO): Sending 529 bytes from file Logs/20190719T031420/Express0001.lzma
2019-07-19T03:22:15.370Z,1563506535.370 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Express0001.lzma.bak
2019-07-19T03:22:15.370Z,1563506535.370 [DataOverHttps](INFO): SBD MOMSN=11455899
2019-07-19T03:22:19.842Z,1563506539.842 [Default:CheckIn:Read_Iridium] Stopped
2019-07-19T03:22:19.843Z,1563506539.843 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-19T03:22:19.843Z,1563506539.843 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T03:22:50.940Z,1563506570.940 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:22:50.941Z,1563506570.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 40
2019-07-19T03:22:50.941Z,1563506570.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:22:50.952Z,1563506570.952 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:22:51.333Z,1563506571.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:22:51.334Z,1563506571.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 40
2019-07-19T03:24:52.137Z,1563506692.137 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:24:52.137Z,1563506692.137 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 41
2019-07-19T03:24:52.137Z,1563506692.137 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:24:52.148Z,1563506692.148 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:24:52.554Z,1563506692.554 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:24:52.554Z,1563506692.554 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 41
2019-07-19T03:26:53.317Z,1563506813.317 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:26:53.317Z,1563506813.317 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 42
2019-07-19T03:26:53.317Z,1563506813.317 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:26:53.328Z,1563506813.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:26:53.731Z,1563506813.731 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:26:53.731Z,1563506813.731 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 42
2019-07-19T03:27:20.388Z,1563506840.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T03:27:20.388Z,1563506840.388 [Default:CheckIn:C.Wait] Stopped
2019-07-19T03:27:20.388Z,1563506840.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T03:27:20.388Z,1563506840.388 [Default:CheckIn:D] Running Loop=1
2019-07-19T03:27:20.795Z,1563506840.795 [Default:CheckIn:D] Stopped
2019-07-19T03:27:20.795Z,1563506840.795 [Default:CheckIn:E] Running Loop=1
2019-07-19T03:27:21.197Z,1563506841.197 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.942603 min 
2019-07-19T03:27:21.197Z,1563506841.197 [Default:CheckIn:E] Stopped
2019-07-19T03:27:21.197Z,1563506841.197 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T03:27:21.197Z,1563506841.197 [Default:CheckIn] Stopped
2019-07-19T03:27:21.197Z,1563506841.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T03:27:21.197Z,1563506841.197 [Default:CheckIn](INFO): Running loop #6
2019-07-19T03:27:21.198Z,1563506841.198 [Default:CheckIn] Running Loop=6
2019-07-19T03:27:21.198Z,1563506841.198 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T03:27:21.198Z,1563506841.198 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T03:27:22.801Z,1563506842.801 [NAL9602](DEBUG): Fix Requested
2019-07-19T03:27:53.899Z,1563506873.899 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-07-19T03:27:53.973Z,1563506873.973 [NAL9602](FAULT): received: 
+CSQ:0

OK927, 2, 0, 0, 0

OK
2019-07-19T03:27:53.973Z,1563506873.973 [NAL9602] Data Fault, FailCount= 2
2019-07-19T03:27:53.974Z,1563506873.974 [NAL9602](ERROR): Data Fault
2019-07-19T03:27:54.012Z,1563506874.012 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T03:27:54.302Z,1563506874.302 [NAL9602](INFO): Powering down
2019-07-19T03:27:55.144Z,1563506875.144 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T03:27:55.144Z,1563506875.144 [NAL9602] No Fault, FailCount= 2
2019-07-19T03:28:24.602Z,1563506904.602 [NAL9602](INFO): Powering up NAL9602
2019-07-19T03:28:35.510Z,1563506915.510 [NAL9602](INFO): NAL9602 initialized
2019-07-19T03:28:36.337Z,1563506916.337 [NAL9602](DEBUG): Fix Requested
2019-07-19T03:28:54.532Z,1563506934.532 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:28:54.532Z,1563506934.532 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 43
2019-07-19T03:28:54.532Z,1563506934.532 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:28:54.572Z,1563506934.572 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:28:54.950Z,1563506934.950 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:28:54.950Z,1563506934.950 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 43
2019-07-19T03:30:55.737Z,1563507055.737 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:30:55.737Z,1563507055.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 44
2019-07-19T03:30:55.737Z,1563507055.737 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:30:55.748Z,1563507055.748 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:30:56.135Z,1563507056.135 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:30:56.135Z,1563507056.135 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 44
2019-07-19T03:32:21.396Z,1563507141.396 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T03:27:21.2Z
2019-07-19T03:32:21.396Z,1563507141.396 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T03:32:21.396Z,1563507141.396 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T03:32:24.982Z,1563507144.982 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T03:32:30.760Z,1563507150.760 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190719T031420/Courier0003.lzma
2019-07-19T03:32:31.566Z,1563507151.566 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Courier0003.lzma.bak
2019-07-19T03:32:31.567Z,1563507151.567 [DataOverHttps](INFO): SBD MOMSN=11455911
2019-07-19T03:32:48.701Z,1563507168.701 [DataOverHttps](INFO): Sending 305 bytes from file Logs/20190719T031420/Express0004.lzma
2019-07-19T03:32:49.506Z,1563507169.506 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Express0004.lzma.bak
2019-07-19T03:32:49.507Z,1563507169.507 [DataOverHttps](INFO): SBD MOMSN=11455913
2019-07-19T03:32:54.107Z,1563507174.107 [Default:CheckIn:Read_Iridium] Stopped
2019-07-19T03:32:54.107Z,1563507174.107 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-19T03:32:54.107Z,1563507174.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T03:32:55.685Z,1563507175.685 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-19T03:32:56.937Z,1563507176.937 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:32:56.937Z,1563507176.937 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 45
2019-07-19T03:32:56.937Z,1563507176.937 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:32:56.952Z,1563507176.952 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:32:57.349Z,1563507177.349 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:32:57.349Z,1563507177.349 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 45
2019-07-19T03:34:58.151Z,1563507298.151 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:34:58.151Z,1563507298.151 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 46
2019-07-19T03:34:58.151Z,1563507298.151 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:34:58.162Z,1563507298.162 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:34:58.566Z,1563507298.566 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:34:58.566Z,1563507298.566 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 46
2019-07-19T03:36:30.255Z,1563507390.255 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-07-19T03:36:30.255Z,1563507390.255 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-07-19T03:36:30.256Z,1563507390.256 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2019-07-19T03:36:30.259Z,1563507390.259 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2019-07-19T03:36:59.346Z,1563507419.346 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:36:59.346Z,1563507419.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 47
2019-07-19T03:36:59.346Z,1563507419.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:36:59.357Z,1563507419.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:36:59.762Z,1563507419.762 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:36:59.762Z,1563507419.762 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 47
2019-07-19T03:37:54.696Z,1563507474.696 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T03:37:54.696Z,1563507474.696 [Default:CheckIn:C.Wait] Stopped
2019-07-19T03:37:54.696Z,1563507474.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T03:37:54.696Z,1563507474.696 [Default:CheckIn:D] Running Loop=1
2019-07-19T03:37:55.106Z,1563507475.106 [Default:CheckIn:D] Stopped
2019-07-19T03:37:55.106Z,1563507475.106 [Default:CheckIn:E] Running Loop=1
2019-07-19T03:37:55.511Z,1563507475.511 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.514445 min 
2019-07-19T03:37:55.511Z,1563507475.511 [Default:CheckIn:E] Stopped
2019-07-19T03:37:55.511Z,1563507475.511 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T03:37:55.511Z,1563507475.511 [Default:CheckIn] Stopped
2019-07-19T03:37:55.511Z,1563507475.511 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T03:37:55.511Z,1563507475.511 [Default:CheckIn](INFO): Running loop #7
2019-07-19T03:37:55.512Z,1563507475.512 [Default:CheckIn] Running Loop=7
2019-07-19T03:37:55.512Z,1563507475.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T03:37:55.512Z,1563507475.512 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T03:37:57.091Z,1563507477.091 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-19T03:37:57.091Z,1563507477.091 [NAL9602] Data Fault, FailCount= 3
2019-07-19T03:37:57.091Z,1563507477.091 [NAL9602](ERROR): Data Fault
2019-07-19T03:37:57.122Z,1563507477.122 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T03:37:57.502Z,1563507477.502 [NAL9602](INFO): Powering down
2019-07-19T03:37:58.342Z,1563507478.342 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T03:37:58.342Z,1563507478.342 [NAL9602] No Fault, FailCount= 3
2019-07-19T03:38:27.806Z,1563507507.806 [NAL9602](INFO): Powering up NAL9602
2019-07-19T03:38:38.710Z,1563507518.710 [NAL9602](INFO): NAL9602 initialized
2019-07-19T03:38:39.529Z,1563507519.529 [NAL9602](DEBUG): Fix Requested
2019-07-19T03:39:00.546Z,1563507540.546 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:39:00.546Z,1563507540.546 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 48
2019-07-19T03:39:00.546Z,1563507540.546 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:39:00.589Z,1563507540.589 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:39:00.962Z,1563507540.962 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:39:00.962Z,1563507540.962 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 48
2019-07-19T03:41:01.746Z,1563507661.746 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:41:01.746Z,1563507661.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 49
2019-07-19T03:41:01.746Z,1563507661.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:41:01.757Z,1563507661.757 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:41:02.165Z,1563507662.165 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:41:02.165Z,1563507662.165 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 49
2019-07-19T03:42:30.695Z,1563507750.695 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/sample.xml
2019-07-19T03:42:30.695Z,1563507750.695 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
2019-07-19T03:42:30.752Z,1563507750.752 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
2019-07-19T03:42:30.756Z,1563507750.756 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
2019-07-19T03:42:30.759Z,1563507750.759 [MissionManager](INFO): DefineArg sample.CartridgeType = -4.000000 count
2019-07-19T03:42:30.763Z,1563507750.763 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 2.000000 count
2019-07-19T03:42:30.766Z,1563507750.766 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 1.000000 min
2019-07-19T03:42:30.768Z,1563507750.768 [sample:A.AbortSample](DEBUG): Construct.
2019-07-19T03:42:30.769Z,1563507750.769 [sample:B.Pitch](DEBUG): Construct.
2019-07-19T03:42:30.775Z,1563507750.775 [sample:C.SetSpeed](DEBUG): Construct.
2019-07-19T03:42:30.778Z,1563507750.778 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
2019-07-19T03:42:30.912Z,1563507750.912 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
2019-07-19T03:42:30.915Z,1563507750.915 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 10.000000 s
2019-07-19T03:42:30.930Z,1563507750.930 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
2019-07-19T03:42:30.933Z,1563507750.933 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
2019-07-19T03:42:30.936Z,1563507750.936 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool
2019-07-19T03:42:30.940Z,1563507750.940 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool
2019-07-19T03:42:30.955Z,1563507750.955 [MissionManager](INFO): DefineArg sample:SampleAtDepth.ESPCartridgeType = nan count
2019-07-19T03:42:30.964Z,1563507750.964 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2019-07-19T03:42:30.984Z,1563507750.984 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2019-07-19T03:42:31.013Z,1563507751.013 [MissionManager](INFO): DefineArg sample:SampleAtDepth.BuoyancyNeutral = 377.999997 cc
2019-07-19T03:42:31.044Z,1563507751.044 [sample:SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Construct.
2019-07-19T03:42:31.070Z,1563507751.070 [sample:SampleAtDepth:ApproachDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T03:42:31.072Z,1563507751.072 [sample:SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Construct.
2019-07-19T03:42:31.092Z,1563507751.092 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Construct Wait.
2019-07-19T03:42:31.101Z,1563507751.101 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T03:42:31.131Z,1563507751.131 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-07-19T03:42:31.132Z,1563507751.132 [MissionManager](ERROR): Slate does not contain celsius
2019-07-19T03:42:31.132Z,1563507751.132 [MissionManager](ERROR): Slate does not contain WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-07-19T03:42:31.132Z,1563507751.132 [MissionManager](ERROR): Slate does not contain microgram_per_liter
2019-07-19T03:42:31.136Z,1563507751.136 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T03:42:31.162Z,1563507751.162 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-07-19T03:42:31.163Z,1563507751.163 [MissionManager](ERROR): Slate does not contain celsius
2019-07-19T03:42:31.163Z,1563507751.163 [MissionManager](ERROR): Slate does not contain WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-07-19T03:42:31.163Z,1563507751.163 [MissionManager](ERROR): Slate does not contain microgram_per_liter
2019-07-19T03:42:31.198Z,1563507751.198 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="sample">

    <Description>
        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.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth to sample at.
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="CartridgeType"><Description>
        Specifies ESP cartridge type code.
    </Description><Units:count/><Value>-4</Value></DefineArg>
    
    <DefineArg Name="NumberOfSamples"><Description>
        Number of samples to take.
    </Description><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="WaitBeforeSample"><Description>
        How long to wait between samples. This starts *before* the first sample
        to exercise the SettleTime setting in the insert aggregate.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

     <Trigger:AbortSample>
     	  <Parallel/>
     </Trigger:AbortSample>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <Insert Filename="Insert/SampleAtDepth.xml"/>

    <Assign><Sequence/><Arg Name="SampleAtDepth:TargetDepth"/><Arg Name="Depth"/></Assign>

    <Assign><Sequence/><Arg Name="SampleAtDepth:SettleTime"/><Arg Name="WaitBeforeSample"/></Assign>

    <Assign><Sequence/><Arg Name="SampleAtDepth:ESPCartridgeType"/><Arg Name="CartridgeType"/></Assign>

    <Aggregate Id="SampleRepeater">

        <Sequence Repeat="NumberOfSamples"/>

        <Call Id="Sample" RefId="SampleAtDepth"/>

    </Aggregate>

</Mission>


2019-07-19T03:42:31.200Z,1563507751.200 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/sample.xml
2019-07-19T03:42:31.451Z,1563507751.451 [Default] Stopped
2019-07-19T03:42:31.451Z,1563507751.451 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-19T03:42:31.451Z,1563507751.451 [Default:B.GoToSurface] Stopped
2019-07-19T03:42:31.451Z,1563507751.451 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-19T03:42:31.451Z,1563507751.451 [Default:CheckIn] Stopped
2019-07-19T03:42:31.452Z,1563507751.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T03:42:31.452Z,1563507751.452 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T03:42:31.452Z,1563507751.452 [MissionManager](IMPORTANT): Started mission sample
2019-07-19T03:42:31.452Z,1563507751.452 [sample] Running Loop=1
2019-07-19T03:42:31.452Z,1563507751.452 [sample](DEBUG): Aggregate::initialize sample
2019-07-19T03:42:31.452Z,1563507751.452 [sample:A.AbortSample] Running Loop=1
2019-07-19T03:42:31.452Z,1563507751.452 [sample:A.AbortSample](INFO): Initializing AbortSample.
2019-07-19T03:42:31.452Z,1563507751.452 [sample:B.Pitch] Running Loop=1
2019-07-19T03:42:31.453Z,1563507751.453 [sample:B.Pitch](DEBUG): Initialize.
2019-07-19T03:42:31.457Z,1563507751.457 [sample:C.SetSpeed] Running Loop=1
2019-07-19T03:42:31.457Z,1563507751.457 [sample:C.SetSpeed](DEBUG): Initialize.
2019-07-19T03:42:31.457Z,1563507751.457 [sample:E] Running Loop=1
2019-07-19T03:42:31.458Z,1563507751.458 [sample:E] Stopped
2019-07-19T03:42:31.458Z,1563507751.458 [sample:F] Running Loop=1
2019-07-19T03:42:31.458Z,1563507751.458 [sample:C.SetSpeed] Running Loop=1
2019-07-19T03:42:31.458Z,1563507751.458 [sample:B.Pitch] Running Loop=1
2019-07-19T03:42:31.458Z,1563507751.458 [sample:A.AbortSample] Running Loop=1
2019-07-19T03:42:31.843Z,1563507751.843 [sample:F] Stopped
2019-07-19T03:42:31.843Z,1563507751.843 [sample:G] Running Loop=1
2019-07-19T03:42:32.259Z,1563507752.259 [sample:G] Stopped
2019-07-19T03:42:32.259Z,1563507752.259 [sample:SampleRepeater] Running Loop=1
2019-07-19T03:42:32.259Z,1563507752.259 [sample:SampleRepeater](DEBUG): Aggregate::initialize sample:SampleRepeater
2019-07-19T03:42:32.259Z,1563507752.259 [sample:SampleRepeater:Sample] Running Loop=1
2019-07-19T03:42:32.259Z,1563507752.259 [sample:SampleRepeater:Sample](DEBUG): Aggregate::initialize sample:SampleRepeater:Sample
2019-07-19T03:42:32.667Z,1563507752.667 [sample:SampleAtDepth] Running Loop=1
2019-07-19T03:42:32.667Z,1563507752.667 [sample:SampleAtDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth
2019-07-19T03:42:32.668Z,1563507752.668 [sample:SampleAtDepth:ApproachDepth] Running Loop=1
2019-07-19T03:42:32.668Z,1563507752.668 [sample:SampleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth:ApproachDepth
2019-07-19T03:42:32.668Z,1563507752.668 [sample:SampleAtDepth:ApproachDepth:A] Running Loop=1
2019-07-19T03:42:32.668Z,1563507752.668 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2019-07-19T03:42:32.668Z,1563507752.668 [sample:SampleAtDepth:ApproachDepth:A] Stopped
2019-07-19T03:42:32.669Z,1563507752.669 [sample:SampleAtDepth:ApproachDepth:B.Pitch] Running Loop=1
2019-07-19T03:42:32.669Z,1563507752.669 [sample:SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Initialize.
2019-07-19T03:43:01.783Z,1563507781.783 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 7.000000 meter
2019-07-19T03:43:01.784Z,1563507781.784 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2019-07-19T03:43:02.182Z,1563507782.182 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-19T03:43:02.548Z,1563507782.548 [sample:SampleAtDepth:ApproachDepth:B.Pitch] Stopped
2019-07-19T03:43:02.548Z,1563507782.548 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
2019-07-19T03:43:02.548Z,1563507782.548 [sample:SampleAtDepth:ApproachDepth] Stopped
2019-07-19T03:43:02.548Z,1563507782.548 [sample:SampleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:ApproachDepth
2019-07-19T03:43:02.549Z,1563507782.549 [sample:SampleAtDepth:SampleWrapper] Running Loop=1
2019-07-19T03:43:02.549Z,1563507782.549 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper
2019-07-19T03:43:02.549Z,1563507782.549 [sample:SampleAtDepth:SampleWrapper:A.Pitch] Running Loop=1
2019-07-19T03:43:02.549Z,1563507782.549 [sample:SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Initialize.
2019-07-19T03:43:02.549Z,1563507782.549 [sample:SampleAtDepth:SampleWrapper:B] Running Loop=1
2019-07-19T03:43:02.954Z,1563507782.954 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:43:02.954Z,1563507782.954 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 50
2019-07-19T03:43:02.954Z,1563507782.954 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:43:02.958Z,1563507782.958 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 7.000000 m , settling for 1.000000 min 
2019-07-19T03:43:02.958Z,1563507782.958 [sample:SampleAtDepth:SampleWrapper:B] Stopped
2019-07-19T03:43:02.958Z,1563507782.958 [sample:SampleAtDepth:SampleWrapper:C.Wait] Running Loop=1
2019-07-19T03:43:02.958Z,1563507782.958 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T03:43:02.958Z,1563507782.958 [sample:SampleAtDepth:SampleWrapper:A.Pitch] Running Loop=1
2019-07-19T03:43:02.993Z,1563507782.993 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:43:03.374Z,1563507783.374 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:43:03.374Z,1563507783.374 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 50
2019-07-19T03:43:41.710Z,1563507821.710 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T03:44:02.657Z,1563507842.657 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2019-07-19T03:44:02.893Z,1563507842.893 [Radio_Surface](INFO): Powering down
2019-07-19T03:44:03.551Z,1563507843.551 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
2019-07-19T03:44:03.551Z,1563507843.551 [sample:SampleAtDepth:SampleWrapper:C.Wait] Stopped
2019-07-19T03:44:03.552Z,1563507843.552 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T03:44:03.552Z,1563507843.552 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Running Loop=1
2019-07-19T03:44:03.552Z,1563507843.552 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler
2019-07-19T03:44:03.552Z,1563507843.552 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1
2019-07-19T03:44:03.552Z,1563507843.552 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Running Loop=1
2019-07-19T03:44:03.552Z,1563507843.552 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Running Loop=1
2019-07-19T03:44:03.955Z,1563507843.955 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Stopped
2019-07-19T03:44:03.955Z,1563507843.955 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler
2019-07-19T03:44:03.955Z,1563507843.955 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Stopped
2019-07-19T03:44:03.955Z,1563507843.955 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Stopped
2019-07-19T03:44:03.956Z,1563507843.956 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Stopped
2019-07-19T03:44:03.956Z,1563507843.956 [sample:SampleAtDepth:SampleWrapper:SampleESP] Running Loop=1
2019-07-19T03:44:03.956Z,1563507843.956 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleESP
2019-07-19T03:44:03.956Z,1563507843.956 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge] Running Loop=1
2019-07-19T03:44:03.956Z,1563507843.956 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
2019-07-19T03:44:03.956Z,1563507843.956 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A] Running Loop=1
2019-07-19T03:44:04.358Z,1563507844.358 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A] Stopped
2019-07-19T03:44:04.358Z,1563507844.358 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
2019-07-19T03:44:04.358Z,1563507844.358 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge] Stopped
2019-07-19T03:44:04.358Z,1563507844.358 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
2019-07-19T03:44:04.358Z,1563507844.358 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Running Loop=1
2019-07-19T03:44:04.767Z,1563507844.767 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sampling
2019-07-19T03:44:06.014Z,1563507846.014 [ESPComponent](INFO): powering up ESP
2019-07-19T03:44:06.033Z,1563507846.033 [ESPComponent](INFO): powering up ESP secondary power supply
2019-07-19T03:44:06.049Z,1563507846.049 [ESPComponent](IMPORTANT): Starting PPPD with command:/sbin/pppd xonxoff asyncmap A0000 /dev/ttyTX1 linkname esp noauth local lock 115200 10.89.5.2:10.89.5.3 persist maxfail 0 holdoff 10 lcp-echo-interval 60 lcp-echo-failure 3 proxyarp ktune deflate 12 ms-dns 8.8.8.8
2019-07-19T03:44:06.152Z,1563507846.152 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=150.0000)
2019-07-19T03:44:06.417Z,1563507846.417 [ESPComponent](INFO): console:
2019-07-19T03:44:07.154Z,1563507847.154 [ESPComponent](INFO): console:

2019-07-19T03:44:07.558Z,1563507847.558 [ESPComponent](INFO): console:NAND:  256 MiB
2019-07-19T03:44:07.962Z,1563507847.962 [ESPComponent](INFO): console:
BOOTMODE: SDMMC
2019-07-19T03:44:08.366Z,1563507848.366 [ESPComponent](INFO): console:

2019-07-19T03:44:08.770Z,1563507848.770 [ESPComponent](INFO): console:

2019-07-19T03:44:09.174Z,1563507849.174 [ESPComponent](INFO): console:
U-Boot 2009.11-mbariC (Apr 02 2019 - 18:00:17)
2019-07-19T03:44:09.578Z,1563507849.578 [ESPComponent](INFO): console:

2019-07-19T03:44:09.982Z,1563507849.982 [ESPComponent](INFO): console:
MBARI LPC31xx ESP 3G
2019-07-19T03:44:10.386Z,1563507850.386 [ESPComponent](INFO): console:
flash params are already written into flash
2019-07-19T03:44:10.790Z,1563507850.790 [ESPComponent](INFO): console:
DRAM:  64 MB
2019-07-19T03:44:11.194Z,1563507851.194 [ESPComponent](INFO): console:
In:    serial
2019-07-19T03:44:11.598Z,1563507851.598 [ESPComponent](INFO): console:
Out:   serial
2019-07-19T03:44:12.002Z,1563507852.002 [ESPComponent](INFO): console:
Err:   serial
2019-07-19T03:44:12.406Z,1563507852.406 [ESPComponent](INFO): console:
Net:   ks8851_mll
2019-07-19T03:44:12.415Z,1563507852.415 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-19T03:44:12.810Z,1563507852.810 [ESPComponent](INFO): console:
Hit <ctrl-C> to stop autoboot:  1  0 
2019-07-19T03:44:13.214Z,1563507853.214 [ESPComponent](INFO): console:
mmc1 is available
2019-07-19T03:44:13.618Z,1563507853.618 [ESPComponent](INFO): console:
reading uImage
2019-07-19T03:44:14.022Z,1563507854.022 [ESPComponent](INFO): console:

2019-07-19T03:44:14.426Z,1563507854.426 [ESPComponent](INFO): console:
1681452 bytes read
2019-07-19T03:44:14.830Z,1563507854.830 [ESPComponent](INFO): console:
## Booting kernel from Legacy Image at 31000000 ...
2019-07-19T03:44:15.235Z,1563507855.235 [ESPComponent](INFO): console:
   Image Name:   Linux-2.6.33-ea3131-mbari6
2019-07-19T03:44:15.639Z,1563507855.639 [ESPComponent](INFO): console:
   Image Type:   ARM Linux Kernel Image (uncompressed)
2019-07-19T03:44:16.042Z,1563507856.042 [ESPComponent](INFO): console:
   Data Size:    1681388 Bytes =  1.6 MB
2019-07-19T03:44:16.446Z,1563507856.446 [ESPComponent](INFO): console:
   Load Address: 30008000
2019-07-19T03:44:16.850Z,1563507856.850 [ESPComponent](INFO): console:
   Entry Point:  30008000
2019-07-19T03:44:17.254Z,1563507857.254 [ESPComponent](INFO): console:
   Verifying Checksum ... OK
2019-07-19T03:44:17.658Z,1563507857.658 [ESPComponent](INFO): console:
   Loading Kernel Image ... OK
2019-07-19T03:44:18.062Z,1563507858.062 [ESPComponent](INFO): console:
OK
2019-07-19T03:44:18.465Z,1563507858.465 [ESPComponent](INFO): console:

2019-07-19T03:44:18.870Z,1563507858.870 [ESPComponent](INFO): console:
Starting kernel ...
2019-07-19T03:44:19.274Z,1563507859.274 [ESPComponent](INFO): console:

2019-07-19T03:44:19.678Z,1563507859.678 [ESPComponent](INFO): console:
Uncompressing Linux... done, booting the kernel.
2019-07-19T03:44:20.082Z,1563507860.082 [ESPComponent](INFO): console:
[    0.00] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=00053177
2019-07-19T03:44:20.487Z,1563507860.487 [ESPComponent](INFO): console:
[    0.00] CPU: VIVT data cache, VIVT instruction cache
2019-07-19T03:44:20.890Z,1563507860.890 [ESPComponent](INFO): console:
[    0.00] Machine: NXP EA313X
2019-07-19T03:44:21.294Z,1563507861.294 [ESPComponent](INFO): console:
[    0.00] Memory policy: ECC disabled, Data cache writeback
2019-07-19T03:44:21.699Z,1563507861.699 [ESPComponent](INFO): console:
[    0.00] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 16256
2019-07-19T03:44:22.102Z,1563507862.102 [ESPComponent](INFO): console:
[    0.24] Mount-cache hash table entries: 512
2019-07-19T03:44:22.506Z,1563507862.506 [ESPComponent](INFO): console:
[    0.29] MBARI ESP 3G
2019-07-19T03:44:22.910Z,1563507862.910 [ESPComponent](INFO): console:
[    0.39] bio: create slab <bio-0> at 0
2019-07-19T03:44:23.315Z,1563507863.315 [ESPComponent](INFO): console:
[    0.46c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T03:44:23.719Z,1563507863.719 [ESPComponent](INFO): console:
[    0.48] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T03:44:24.123Z,1563507864.123 [ESPComponent](INFO): console:
[    0.50] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T03:44:24.527Z,1563507864.527 [ESPComponent](INFO): console:
[    0.52] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T03:44:24.931Z,1563507864.931 [ESPComponent](INFO): console:
[    0.54] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T03:44:25.335Z,1563507865.335 [ESPComponent](INFO): console:
[    0.56] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T03:44:25.739Z,1563507865.739 [ESPComponent](INFO): console:
[    0.56] NetWinder Floating Point Emulator V0.97 (double precision)
2019-07-19T03:44:26.144Z,1563507866.144 [ESPComponent](INFO): console:
[    0.58] yaffs Dec 14 2018 09:19:05 Installing. 
2019-07-19T03:44:26.547Z,1563507866.547 [ESPComponent](INFO): console:
[    0.72] m25p80 spi0.3: m25p80 found s25sl032a flash
2019-07-19T03:44:26.951Z,1563507866.951 [ESPComponent](INFO): console:
[    2.36] VFS: Mounted root (ext2 filesystem) readonly on device 179:2.
2019-07-19T03:44:27.353Z,1563507867.353 [ESPComponent](INFO): console:

2019-07-19T03:44:27.758Z,1563507867.758 [ESPComponent](INFO): console:INIT: version 2.85 booting
2019-07-19T03:44:28.161Z,1563507868.161 [ESPComponent](INFO): console:

2019-07-19T03:44:28.567Z,1563507868.567 [ESPComponent](INFO): console:MBARI ESP Embedded Linux    http://www.mbari.org   mailto:brent@mbari.org
2019-07-19T03:44:28.970Z,1563507868.970 [ESPComponent](INFO): console:
ESPhonu
2019-07-19T03:44:29.373Z,1563507869.373 [ESPComponent](INFO): console:

2019-07-19T03:44:29.778Z,1563507869.778 [ESPComponent](INFO): console:INIT: Entering runlevel: 3
2019-07-19T03:44:30.182Z,1563507870.182 [ESPComponent](INFO): console:

2019-07-19T03:44:30.586Z,1563507870.586 [ESPComponent](INFO): console:Loading kernel modules
2019-07-19T03:44:30.991Z,1563507870.991 [ESPComponent](INFO): console:
Mounting filesystems and adding swapfiles listed in /etc/fstab
2019-07-19T03:44:31.395Z,1563507871.395 [ESPComponent](INFO): console:
mount: /dev/mmcblk0p2 already mounted or /card busy
2019-07-19T03:44:31.798Z,1563507871.798 [ESPComponent](INFO): console:
Starting hotplug events dispatcher udevd
2019-07-19T03:44:32.202Z,1563507872.202 [ESPComponent](INFO): console:
Set system clock to:  Thu Jul 18 20:41:25 PDT 2019
2019-07-19T03:44:32.606Z,1563507872.606 [ESPComponent](INFO): console:
Starting syslogd klogd...
2019-07-19T03:44:33.010Z,1563507873.010 [ESPComponent](INFO): console:
Bringing up interface lo ...
2019-07-19T03:44:33.414Z,1563507873.414 [ESPComponent](INFO): console:
lo IP=127.0.0.1
2019-07-19T03:44:33.818Z,1563507873.818 [ESPComponent](INFO): console:
Packet forwarding enabled
2019-07-19T03:44:34.222Z,1563507874.222 [ESPComponent](INFO): console:
Bringing up interface ppp2 ...
2019-07-19T03:44:34.626Z,1563507874.626 [ESPComponent](INFO): console:
Bringing up interface eth0 ...
2019-07-19T03:44:35.031Z,1563507875.031 [ESPComponent](INFO): console:
Determining IP configuration for eth0....udhcpc (v1.11.2) started
2019-07-19T03:44:35.434Z,1563507875.434 [ESPComponent](INFO): console:
Sending discover...
2019-07-19T03:44:35.838Z,1563507875.838 [ESPComponent](INFO): console:
Sending discover...
2019-07-19T03:44:36.242Z,1563507876.242 [ESPComponent](INFO): console:
Sending discover...
2019-07-19T03:44:36.717Z,1563507876.717 [ESPComponent](INFO): console:

2019-07-19T03:44:39.474Z,1563507879.474 [ESPComponent](INFO): console:No lease, forking to background
2019-07-19T03:44:39.878Z,1563507879.878 [ESPComponent](INFO): console:
Bringing up interface eth0:10 ...
2019-07-19T03:44:40.282Z,1563507880.282 [ESPComponent](INFO): console:
eth0:10 IP=10.10.10.10
2019-07-19T03:44:40.686Z,1563507880.686 [ESPComponent](INFO): console:
Bringing up interface wlan0 ...
2019-07-19T03:44:41.091Z,1563507881.091 [ESPComponent](INFO): console:
Successfully initialized wpa_supplicant
2019-07-19T03:44:41.565Z,1563507881.565 [ESPComponent](INFO): console:

2019-07-19T03:44:44.322Z,1563507884.322 [ESPComponent](INFO): console:rfkill: Cannot open RFKILL control device
2019-07-19T03:44:44.797Z,1563507884.797 [ESPComponent](INFO): console:

2019-07-19T03:44:47.625Z,1563507887.625 [ESPComponent](INFO): console:Determining IP configuration for wlan0....
2019-07-19T03:44:47.958Z,1563507887.958 [ESPComponent](INFO): console:udhcpc (v1.11.2) started
2019-07-19T03:44:48.433Z,1563507888.433 [ESPComponent](INFO): console:

2019-07-19T03:44:50.382Z,1563507890.382 [ESPComponent](INFO): console:Sending discover...
2019-07-19T03:44:50.857Z,1563507890.857 [ESPComponent](INFO): console:

2019-07-19T03:44:52.805Z,1563507892.805 [ESPComponent](IMPORTANT): ESP has connected as client
2019-07-19T03:44:53.618Z,1563507893.618 [ESPComponent](IMPORTANT): [sample #2] Setting cartridge argument to -4
2019-07-19T03:44:53.618Z,1563507893.618 [ESPComponent](IMPORTANT): [sample #2] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
2019-07-19T03:44:53.619Z,1563507893.619 [ESPComponent](DEBUG): Writing samplingActive=1, sampleNumber=2
2019-07-19T03:44:53.652Z,1563507893.652 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Stopped
2019-07-19T03:44:53.652Z,1563507893.652 [sample:SampleAtDepth:SampleWrapper:SampleESP:C] Running Loop=1
2019-07-19T03:44:54.056Z,1563507894.056 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 7.000000 m inwater withchlorophyll fluorescence.
2019-07-19T03:44:54.056Z,1563507894.056 [sample:SampleAtDepth:SampleWrapper:SampleESP:C] Stopped
2019-07-19T03:44:54.056Z,1563507894.056 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP] Running Loop=1
2019-07-19T03:44:54.422Z,1563507894.422 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PREPARING_SHOW_LOG
2019-07-19T03:44:54.456Z,1563507894.456 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sample_number
2019-07-19T03:44:54.826Z,1563507894.826 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PREPARING_SHOW_STATUS
2019-07-19T03:44:56.846Z,1563507896.846 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_LOADING_CARTRIDGE
2019-07-19T03:45:04.150Z,1563507904.150 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:45:04.150Z,1563507904.150 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 51
2019-07-19T03:45:04.150Z,1563507904.150 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:45:04.162Z,1563507904.162 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:45:04.563Z,1563507904.563 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:45:04.564Z,1563507904.564 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 51
2019-07-19T03:46:45.065Z,1563508005.065 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.183624
2019-07-19T03:47:05.352Z,1563508025.352 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:47:05.352Z,1563508025.352 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 52
2019-07-19T03:47:05.352Z,1563508025.352 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:47:05.401Z,1563508025.401 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:47:05.797Z,1563508025.797 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:47:05.798Z,1563508025.798 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 52
2019-07-19T03:49:06.546Z,1563508146.546 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:49:06.546Z,1563508146.546 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 53
2019-07-19T03:49:06.546Z,1563508146.546 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:49:06.558Z,1563508146.558 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:49:06.967Z,1563508146.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:49:06.967Z,1563508146.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 53
2019-07-19T03:49:07.326Z,1563508147.326 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PRIMING (@42,archiveHiBiomass_bac)
2019-07-19T03:49:08.942Z,1563508148.942 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PRIMING (@100.0)
2019-07-19T03:49:09.750Z,1563508149.750 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_FILTERING (@100.0)
2019-07-19T03:51:07.739Z,1563508267.739 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:51:07.740Z,1563508267.740 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 54
2019-07-19T03:51:07.740Z,1563508267.740 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:51:07.752Z,1563508267.752 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:51:08.167Z,1563508268.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:51:08.167Z,1563508268.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 54
2019-07-19T03:51:37.210Z,1563508297.210 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_FILTERING (@0.0)
2019-07-19T03:51:37.614Z,1563508297.614 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
2019-07-19T03:53:08.941Z,1563508388.941 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:53:08.941Z,1563508388.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 55
2019-07-19T03:53:08.941Z,1563508388.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:53:08.953Z,1563508388.953 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:53:09.367Z,1563508389.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:53:09.367Z,1563508389.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 55
2019-07-19T03:55:10.139Z,1563508510.139 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:55:10.140Z,1563508510.140 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 56
2019-07-19T03:55:10.140Z,1563508510.140 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:55:10.152Z,1563508510.152 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:55:10.567Z,1563508510.567 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:55:10.567Z,1563508510.567 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 56
2019-07-19T03:57:11.339Z,1563508631.339 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:57:11.339Z,1563508631.339 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 57
2019-07-19T03:57:11.340Z,1563508631.340 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:57:11.352Z,1563508631.352 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:57:11.770Z,1563508631.770 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:57:11.770Z,1563508631.770 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 57
2019-07-19T03:59:12.552Z,1563508752.552 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T03:59:12.552Z,1563508752.552 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 58
2019-07-19T03:59:12.552Z,1563508752.552 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T03:59:12.565Z,1563508752.565 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T03:59:12.967Z,1563508752.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T03:59:12.967Z,1563508752.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 58
2019-07-19T04:01:13.746Z,1563508873.746 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:01:13.746Z,1563508873.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 59
2019-07-19T04:01:13.746Z,1563508873.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:01:13.758Z,1563508873.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:01:14.167Z,1563508874.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:01:14.167Z,1563508874.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 59
2019-07-19T04:03:14.946Z,1563508994.946 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:03:14.946Z,1563508994.946 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 60
2019-07-19T04:03:14.946Z,1563508994.946 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:03:14.958Z,1563508994.958 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:03:15.367Z,1563508995.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:03:15.367Z,1563508995.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 60
2019-07-19T04:05:16.139Z,1563509116.139 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:05:16.139Z,1563509116.139 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 61
2019-07-19T04:05:16.139Z,1563509116.139 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:05:16.172Z,1563509116.172 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:05:16.577Z,1563509116.577 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:05:16.578Z,1563509116.578 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 61
2019-07-19T04:07:17.346Z,1563509237.346 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:07:17.346Z,1563509237.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 62
2019-07-19T04:07:17.346Z,1563509237.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:07:17.358Z,1563509237.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:07:17.767Z,1563509237.767 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:07:17.767Z,1563509237.767 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 62
2019-07-19T04:09:18.539Z,1563509358.539 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:09:18.539Z,1563509358.539 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 63
2019-07-19T04:09:18.540Z,1563509358.540 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:09:18.552Z,1563509358.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:09:18.967Z,1563509358.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:09:18.967Z,1563509358.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 63
2019-07-19T04:11:19.746Z,1563509479.746 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:11:19.746Z,1563509479.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 64
2019-07-19T04:11:19.746Z,1563509479.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:11:19.758Z,1563509479.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:11:20.167Z,1563509480.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:11:20.167Z,1563509480.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 64
2019-07-19T04:11:30.159Z,1563509490.159 [CommandLine](IMPORTANT): got command show stack
2019-07-19T04:11:30.159Z,1563509490.159 [CommandLine](IMPORTANT): Behavior Stack:

2019-07-19T04:11:30.159Z,1563509490.159 [sample](IMPORTANT): Priority 0: sample:A.AbortSample
2019-07-19T04:11:30.159Z,1563509490.159 [sample](IMPORTANT): Priority 1: sample:B.Pitch
2019-07-19T04:11:30.160Z,1563509490.160 [sample](IMPORTANT): Priority 2: sample:C.SetSpeed
2019-07-19T04:11:30.160Z,1563509490.160 [sample:SampleAtDepth:SampleWrapper](IMPORTANT): Priority 3: sample:SampleAtDepth:SampleWrapper:A.Pitch
2019-07-19T04:11:30.160Z,1563509490.160 [sample:SampleAtDepth:SampleWrapper:SampleESP](IMPORTANT): Priority 4: sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP
2019-07-19T04:13:20.946Z,1563509600.946 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:13:20.946Z,1563509600.946 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 65
2019-07-19T04:13:20.946Z,1563509600.946 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:13:20.958Z,1563509600.958 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:13:21.367Z,1563509601.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:13:21.367Z,1563509601.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 65
2019-07-19T04:15:22.146Z,1563509722.146 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:15:22.146Z,1563509722.146 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 66
2019-07-19T04:15:22.146Z,1563509722.146 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:15:22.158Z,1563509722.158 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:15:22.571Z,1563509722.571 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:15:22.571Z,1563509722.571 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 66
2019-07-19T04:17:23.339Z,1563509843.339 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:17:23.339Z,1563509843.339 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 67
2019-07-19T04:17:23.339Z,1563509843.339 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:17:23.351Z,1563509843.351 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:17:23.767Z,1563509843.767 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:17:23.767Z,1563509843.767 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 67
2019-07-19T04:19:24.546Z,1563509964.546 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:19:24.546Z,1563509964.546 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 68
2019-07-19T04:19:24.546Z,1563509964.546 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:19:24.558Z,1563509964.558 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:19:24.971Z,1563509964.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:19:24.971Z,1563509964.971 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 68
2019-07-19T04:21:25.746Z,1563510085.746 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:21:25.746Z,1563510085.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 69
2019-07-19T04:21:25.746Z,1563510085.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:21:25.758Z,1563510085.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:21:26.167Z,1563510086.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:21:26.167Z,1563510086.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 69
2019-07-19T04:23:26.946Z,1563510206.946 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:23:26.946Z,1563510206.946 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 70
2019-07-19T04:23:26.946Z,1563510206.946 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:23:26.958Z,1563510206.958 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:23:27.363Z,1563510207.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:23:27.363Z,1563510207.363 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 70
2019-07-19T04:25:28.141Z,1563510328.141 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:25:28.141Z,1563510328.141 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 71
2019-07-19T04:25:28.141Z,1563510328.141 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:25:28.153Z,1563510328.153 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:25:28.567Z,1563510328.567 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:25:28.567Z,1563510328.567 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 71
2019-07-19T04:27:29.339Z,1563510449.339 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:27:29.340Z,1563510449.340 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 72
2019-07-19T04:27:29.340Z,1563510449.340 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:27:29.352Z,1563510449.352 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:27:29.767Z,1563510449.767 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:27:29.767Z,1563510449.767 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 72
2019-07-19T04:28:56.999Z,1563510536.999 [CommandLine](IMPORTANT): got command show stack
2019-07-19T04:28:56.999Z,1563510536.999 [CommandLine](IMPORTANT): Behavior Stack:

2019-07-19T04:28:56.999Z,1563510536.999 [sample](IMPORTANT): Priority 0: sample:A.AbortSample
2019-07-19T04:28:56.000Z,1563510537.000 [sample](IMPORTANT): Priority 1: sample:B.Pitch
2019-07-19T04:28:56.000Z,1563510537.000 [sample](IMPORTANT): Priority 2: sample:C.SetSpeed
2019-07-19T04:28:57.000Z,1563510537.000 [sample:SampleAtDepth:SampleWrapper](IMPORTANT): Priority 3: sample:SampleAtDepth:SampleWrapper:A.Pitch
2019-07-19T04:28:57.001Z,1563510537.001 [sample:SampleAtDepth:SampleWrapper:SampleESP](IMPORTANT): Priority 4: sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP
2019-07-19T04:29:30.546Z,1563510570.546 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:29:30.546Z,1563510570.546 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 73
2019-07-19T04:29:30.546Z,1563510570.546 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:29:30.558Z,1563510570.558 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:29:30.967Z,1563510570.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:29:30.967Z,1563510570.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 73
2019-07-19T04:31:31.739Z,1563510691.739 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:31:31.740Z,1563510691.740 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 74
2019-07-19T04:31:31.740Z,1563510691.740 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:31:31.752Z,1563510691.752 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:31:32.164Z,1563510692.164 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:31:32.164Z,1563510692.164 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 74
2019-07-19T04:33:32.950Z,1563510812.950 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:33:32.950Z,1563510812.950 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 75
2019-07-19T04:33:32.950Z,1563510812.950 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:33:32.962Z,1563510812.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:33:33.367Z,1563510813.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:33:33.367Z,1563510813.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 75
2019-07-19T04:35:34.139Z,1563510934.139 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:35:34.140Z,1563510934.140 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 76
2019-07-19T04:35:34.140Z,1563510934.140 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:35:34.152Z,1563510934.152 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:35:34.567Z,1563510934.567 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:35:34.567Z,1563510934.567 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 76
2019-07-19T04:37:35.353Z,1563511055.353 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:37:35.353Z,1563511055.353 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 77
2019-07-19T04:37:35.353Z,1563511055.353 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:37:35.397Z,1563511055.397 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:37:35.764Z,1563511055.764 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:37:35.764Z,1563511055.764 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 77
2019-07-19T04:39:36.547Z,1563511176.547 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:39:36.547Z,1563511176.547 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 78
2019-07-19T04:39:36.547Z,1563511176.547 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:39:36.583Z,1563511176.583 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:39:36.960Z,1563511176.960 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:39:36.960Z,1563511176.960 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 78
2019-07-19T04:41:37.747Z,1563511297.747 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:41:37.747Z,1563511297.747 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 79
2019-07-19T04:41:37.747Z,1563511297.747 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:41:37.759Z,1563511297.759 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:41:38.172Z,1563511298.172 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:41:38.172Z,1563511298.172 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 79
2019-07-19T04:43:38.946Z,1563511418.946 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:43:38.947Z,1563511418.947 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 80
2019-07-19T04:43:38.947Z,1563511418.947 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:43:38.958Z,1563511418.958 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:43:39.367Z,1563511419.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:43:39.367Z,1563511419.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 80
2019-07-19T04:45:40.165Z,1563511540.165 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:45:40.165Z,1563511540.165 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 81
2019-07-19T04:45:40.165Z,1563511540.165 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:45:40.177Z,1563511540.177 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:45:40.568Z,1563511540.568 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:45:40.568Z,1563511540.568 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 81
2019-07-19T04:47:41.350Z,1563511661.350 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:47:41.350Z,1563511661.350 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 82
2019-07-19T04:47:41.350Z,1563511661.350 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:47:41.362Z,1563511661.362 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:47:41.760Z,1563511661.760 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:47:41.760Z,1563511661.760 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 82
2019-07-19T04:49:09.818Z,1563511749.818 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-07-19T04:49:42.550Z,1563511782.550 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:49:42.550Z,1563511782.550 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 83
2019-07-19T04:49:42.550Z,1563511782.550 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:49:42.562Z,1563511782.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:49:42.964Z,1563511782.964 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:49:42.964Z,1563511782.964 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 83
2019-07-19T04:51:43.753Z,1563511903.753 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:51:43.753Z,1563511903.753 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 84
2019-07-19T04:51:43.753Z,1563511903.753 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:51:43.765Z,1563511903.765 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:51:44.167Z,1563511904.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:51:44.167Z,1563511904.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 84
2019-07-19T04:53:12.598Z,1563511992.598 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PAUSED (@1000.0)
2019-07-19T04:53:12.598Z,1563511992.598 [ESPComponent](DEBUG): Writing sampleVolume=1000.000000
2019-07-19T04:53:13.002Z,1563511993.002 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PROCESSING (@1000.0)
2019-07-19T04:53:15.426Z,1563511995.426 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PROCESSED (@42,archiveHiBiomass_bac)
2019-07-19T04:53:44.953Z,1563512024.953 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:53:44.953Z,1563512024.953 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 85
2019-07-19T04:53:44.953Z,1563512024.953 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:53:44.965Z,1563512024.965 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:53:45.368Z,1563512025.368 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:53:45.368Z,1563512025.368 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 85
2019-07-19T04:55:46.169Z,1563512146.169 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:55:46.169Z,1563512146.169 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 86
2019-07-19T04:55:46.169Z,1563512146.169 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:55:46.181Z,1563512146.181 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:55:46.579Z,1563512146.579 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:55:46.579Z,1563512146.579 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 86
2019-07-19T04:57:47.411Z,1563512267.411 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:57:47.411Z,1563512267.411 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 87
2019-07-19T04:57:47.411Z,1563512267.411 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:57:47.423Z,1563512267.423 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:57:47.764Z,1563512267.764 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:57:47.764Z,1563512267.764 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 87
2019-07-19T04:59:02.215Z,1563512342.215 [CommandLine](IMPORTANT): got command show stack
2019-07-19T04:59:02.215Z,1563512342.215 [CommandLine](IMPORTANT): Behavior Stack:

2019-07-19T04:59:02.215Z,1563512342.215 [sample](IMPORTANT): Priority 0: sample:A.AbortSample
2019-07-19T04:59:02.215Z,1563512342.215 [sample](IMPORTANT): Priority 1: sample:B.Pitch
2019-07-19T04:59:02.216Z,1563512342.216 [sample](IMPORTANT): Priority 2: sample:C.SetSpeed
2019-07-19T04:59:02.216Z,1563512342.216 [sample:SampleAtDepth:SampleWrapper](IMPORTANT): Priority 3: sample:SampleAtDepth:SampleWrapper:A.Pitch
2019-07-19T04:59:02.216Z,1563512342.216 [sample:SampleAtDepth:SampleWrapper:SampleESP](IMPORTANT): Priority 4: sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP
2019-07-19T04:59:48.612Z,1563512388.612 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T04:59:48.612Z,1563512388.612 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 88
2019-07-19T04:59:48.612Z,1563512388.612 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T04:59:48.624Z,1563512388.624 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T04:59:48.967Z,1563512388.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T04:59:48.967Z,1563512388.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 88
2019-07-19T05:01:49.761Z,1563512509.761 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:01:49.761Z,1563512509.761 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 89
2019-07-19T05:01:49.761Z,1563512509.761 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:01:49.773Z,1563512509.773 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:01:50.179Z,1563512510.179 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:01:50.179Z,1563512510.179 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 89
2019-07-19T05:03:50.946Z,1563512630.946 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:03:50.946Z,1563512630.946 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 90
2019-07-19T05:03:50.946Z,1563512630.946 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:03:50.958Z,1563512630.958 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:03:51.367Z,1563512631.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:03:51.368Z,1563512631.368 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 90
2019-07-19T05:05:51.757Z,1563512751.757 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:05:51.757Z,1563512751.757 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 91
2019-07-19T05:05:51.757Z,1563512751.757 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:05:51.769Z,1563512751.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:05:52.179Z,1563512752.179 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:05:52.179Z,1563512752.179 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 91
2019-07-19T05:06:04.642Z,1563512764.642 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_STOPPING (@42,archiveHiBiomass_bac)
2019-07-19T05:06:31.710Z,1563512791.710 [ESPComponent](IMPORTANT): [sample #2] ESP sampling sequence completed normally.
2019-07-19T05:06:31.711Z,1563512791.711 [ESPComponent](DEBUG): Writing samplingActive=0, sampleNumber=2
2019-07-19T05:06:31.744Z,1563512791.744 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP] Stopped
2019-07-19T05:06:31.744Z,1563512791.744 [sample:SampleAtDepth:SampleWrapper:SampleESP](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP
2019-07-19T05:06:31.744Z,1563512791.744 [sample:SampleAtDepth:SampleWrapper:SampleESP] Stopped
2019-07-19T05:06:31.746Z,1563512791.746 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleESP
2019-07-19T05:06:31.748Z,1563512791.748 [sample:SampleAtDepth:SampleWrapper](INFO): Completed sample:SampleAtDepth:SampleWrapper
2019-07-19T05:06:31.748Z,1563512791.748 [sample:SampleAtDepth:SampleWrapper] Stopped
2019-07-19T05:06:31.749Z,1563512791.749 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper
2019-07-19T05:06:31.749Z,1563512791.749 [sample:SampleAtDepth:SampleWrapper:A.Pitch] Stopped
2019-07-19T05:06:31.749Z,1563512791.749 [sample:SampleAtDepth](INFO): Completed sample:SampleAtDepth
2019-07-19T05:06:31.749Z,1563512791.749 [sample:SampleAtDepth] Stopped
2019-07-19T05:06:31.749Z,1563512791.749 [sample:SampleAtDepth](DEBUG): Aggregate::uninitialize sample:SampleAtDepth
2019-07-19T05:06:32.114Z,1563512792.114 [ESPComponent](IMPORTANT): [sample #2] ESP log summary report (3 messages):
@20:45:10.11 <LRAUV> Retry #1 of 2
@20:45:26.94 Selecting Cartridge 42
@21:49:58.96 Sampled  1000.0ml

2019-07-19T05:06:32.114Z,1563512792.114 [ESPComponent](INFO): powering down ESP
2019-07-19T05:06:32.115Z,1563512792.115 [ESPComponent](INFO): powering down ESP secondary power supply
2019-07-19T05:06:32.272Z,1563512792.272 [sample:SampleRepeater:Sample](INFO): Completed sample:SampleRepeater:Sample
2019-07-19T05:06:32.272Z,1563512792.272 [sample:SampleRepeater:Sample] Stopped
2019-07-19T05:06:32.273Z,1563512792.273 [sample:SampleRepeater:Sample](DEBUG): Aggregate::uninitialize sample:SampleRepeater:Sample
2019-07-19T05:06:32.273Z,1563512792.273 [sample:SampleRepeater](INFO): Completed sample:SampleRepeater
2019-07-19T05:06:32.273Z,1563512792.273 [sample:SampleRepeater] Stopped
2019-07-19T05:06:32.274Z,1563512792.274 [sample:SampleRepeater](DEBUG): Aggregate::uninitialize sample:SampleRepeater
2019-07-19T05:06:32.274Z,1563512792.274 [sample:SampleRepeater](INFO): Running loop #2
2019-07-19T05:06:32.274Z,1563512792.274 [sample:SampleRepeater] Running Loop=2
2019-07-19T05:06:32.274Z,1563512792.274 [sample:SampleRepeater](DEBUG): Aggregate::initialize sample:SampleRepeater
2019-07-19T05:06:32.274Z,1563512792.274 [sample:SampleRepeater:Sample] Running Loop=1
2019-07-19T05:06:32.274Z,1563512792.274 [sample:SampleRepeater:Sample](DEBUG): Aggregate::initialize sample:SampleRepeater:Sample
2019-07-19T05:06:32.548Z,1563512792.548 [sample:SampleAtDepth] Running Loop=1
2019-07-19T05:06:32.548Z,1563512792.548 [sample:SampleAtDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth
2019-07-19T05:06:32.548Z,1563512792.548 [sample:SampleAtDepth:ApproachDepth] Running Loop=1
2019-07-19T05:06:32.548Z,1563512792.548 [sample:SampleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth:ApproachDepth
2019-07-19T05:06:32.549Z,1563512792.549 [sample:SampleAtDepth:ApproachDepth:A] Running Loop=1
2019-07-19T05:06:32.549Z,1563512792.549 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2019-07-19T05:06:32.549Z,1563512792.549 [sample:SampleAtDepth:ApproachDepth:A] Stopped
2019-07-19T05:06:32.550Z,1563512792.550 [sample:SampleAtDepth:ApproachDepth:B.Pitch] Running Loop=1
2019-07-19T05:06:32.550Z,1563512792.550 [sample:SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Initialize.
2019-07-19T05:06:32.960Z,1563512792.960 [sample:SampleAtDepth:ApproachDepth:B.Pitch] Stopped
2019-07-19T05:06:32.960Z,1563512792.960 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
2019-07-19T05:06:32.960Z,1563512792.960 [sample:SampleAtDepth:ApproachDepth] Stopped
2019-07-19T05:06:32.960Z,1563512792.960 [sample:SampleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:ApproachDepth
2019-07-19T05:06:32.961Z,1563512792.961 [sample:SampleAtDepth:SampleWrapper] Running Loop=1
2019-07-19T05:06:32.961Z,1563512792.961 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper
2019-07-19T05:06:32.961Z,1563512792.961 [sample:SampleAtDepth:SampleWrapper:A.Pitch] Running Loop=1
2019-07-19T05:06:32.961Z,1563512792.961 [sample:SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Initialize.
2019-07-19T05:06:32.961Z,1563512792.961 [sample:SampleAtDepth:SampleWrapper:B] Running Loop=1
2019-07-19T05:06:33.029Z,1563512793.029 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2019-07-19T05:06:33.029Z,1563512793.029 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2019-07-19T05:06:33.029Z,1563512793.029 [BuoyancyServo] Hardware Fault, FailCount= 1
2019-07-19T05:06:33.030Z,1563512793.030 [BuoyancyServo](ERROR): Hardware Fault
2019-07-19T05:06:33.053Z,1563512793.053 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2019-07-19T05:06:33.367Z,1563512793.367 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 7.000000 m , settling for 1.000000 min 
2019-07-19T05:06:33.367Z,1563512793.367 [sample:SampleAtDepth:SampleWrapper:B] Stopped
2019-07-19T05:06:33.368Z,1563512793.368 [sample:SampleAtDepth:SampleWrapper:C.Wait] Running Loop=1
2019-07-19T05:06:33.368Z,1563512793.368 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T05:06:34.990Z,1563512794.990 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-07-19T05:06:34.990Z,1563512794.990 [BuoyancyServo](INFO): Powering down
2019-07-19T05:06:35.783Z,1563512795.783 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-07-19T05:06:35.783Z,1563512795.783 [BuoyancyServo] No Fault, FailCount= 1
2019-07-19T05:06:36.193Z,1563512796.193 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-07-19T05:06:36.193Z,1563512796.193 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-07-19T05:07:31.128Z,1563512851.128 [BPC1](FAULT): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-07-19T05:07:31.129Z,1563512851.129 [BPC1](FAULT): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-07-19T05:07:31.130Z,1563512851.130 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2019-07-19T05:07:31.132Z,1563512851.132 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2019-07-19T05:07:33.958Z,1563512853.958 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
2019-07-19T05:07:33.958Z,1563512853.958 [sample:SampleAtDepth:SampleWrapper:C.Wait] Stopped
2019-07-19T05:07:33.958Z,1563512853.958 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T05:07:33.958Z,1563512853.958 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Running Loop=1
2019-07-19T05:07:33.959Z,1563512853.959 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler
2019-07-19T05:07:33.959Z,1563512853.959 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1
2019-07-19T05:07:33.959Z,1563512853.959 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Running Loop=1
2019-07-19T05:07:33.959Z,1563512853.959 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Running Loop=1
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Stopped
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Stopped
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Stopped
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Stopped
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleESP] Running Loop=1
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleESP
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge] Running Loop=1
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
2019-07-19T05:07:34.368Z,1563512854.368 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A] Running Loop=1
2019-07-19T05:07:34.776Z,1563512854.776 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A] Stopped
2019-07-19T05:07:34.776Z,1563512854.776 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
2019-07-19T05:07:34.776Z,1563512854.776 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge] Stopped
2019-07-19T05:07:34.776Z,1563512854.776 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
2019-07-19T05:07:34.776Z,1563512854.776 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Running Loop=1
2019-07-19T05:07:36.422Z,1563512856.422 [ESPComponent](INFO): powering up ESP
2019-07-19T05:07:36.441Z,1563512856.441 [ESPComponent](INFO): powering up ESP secondary power supply
2019-07-19T05:07:36.457Z,1563512856.457 [ESPComponent](IMPORTANT): Starting PPPD with command:/sbin/pppd xonxoff asyncmap A0000 /dev/ttyTX1 linkname esp noauth local lock 115200 10.89.5.2:10.89.5.3 persist maxfail 0 holdoff 10 lcp-echo-interval 60 lcp-echo-failure 3 proxyarp ktune deflate 12 ms-dns 8.8.8.8
2019-07-19T05:07:36.572Z,1563512856.572 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=150.0000)
2019-07-19T05:07:36.825Z,1563512856.825 [ESPComponent](INFO): console:
2019-07-19T05:07:37.562Z,1563512857.562 [ESPComponent](INFO): console:

2019-07-19T05:07:37.966Z,1563512857.966 [ESPComponent](INFO): console:NAND:  256 MiB
2019-07-19T05:07:38.370Z,1563512858.370 [ESPComponent](INFO): console:
BOOTMODE: SDMMC
2019-07-19T05:07:38.774Z,1563512858.774 [ESPComponent](INFO): console:

2019-07-19T05:07:39.177Z,1563512859.177 [ESPComponent](INFO): console:

2019-07-19T05:07:39.582Z,1563512859.582 [ESPComponent](INFO): console:
U-Boot 2009.11-mbariC (Apr 02 2019 - 18:00:17)
2019-07-19T05:07:39.986Z,1563512859.986 [ESPComponent](INFO): console:

2019-07-19T05:07:40.390Z,1563512860.390 [ESPComponent](INFO): console:
MBARI LPC31xx ESP 3G
2019-07-19T05:07:40.794Z,1563512860.794 [ESPComponent](INFO): console:
rams are already written into flash
2019-07-19T05:07:41.198Z,1563512861.198 [ESPComponent](INFO): console:
DRAM:  64 MB
2019-07-19T05:07:41.602Z,1563512861.602 [ESPComponent](INFO): console:
In:    serial
2019-07-19T05:07:42.006Z,1563512862.006 [ESPComponent](INFO): console:
Out:   serial
2019-07-19T05:07:42.410Z,1563512862.410 [ESPComponent](INFO): console:
Err:   serial
2019-07-19T05:07:42.814Z,1563512862.814 [ESPComponent](INFO): console:
Net:   ks8851_mll
2019-07-19T05:07:43.218Z,1563512863.218 [ESPComponent](INFO): console:
Hit <ctrl-C> to stop autoboot:  1  0 
2019-07-19T05:07:43.622Z,1563512863.622 [ESPComponent](INFO): console:
mmc1 is available
2019-07-19T05:07:44.026Z,1563512864.026 [ESPComponent](INFO): console:
reading uImage
2019-07-19T05:07:44.429Z,1563512864.429 [ESPComponent](INFO): console:

2019-07-19T05:07:44.834Z,1563512864.834 [ESPComponent](INFO): console:
1681452 bytes read
2019-07-19T05:07:45.238Z,1563512865.238 [ESPComponent](INFO): console:
## Booting kernel from Legacy Image at 31000000 ...
2019-07-19T05:07:45.642Z,1563512865.642 [ESPComponent](INFO): console:
   Image Name:   Linux-2.6.33-ea3131-mbari6
2019-07-19T05:07:46.047Z,1563512866.047 [ESPComponent](INFO): console:
   Image Type:   ARM Linux Kernel Image (uncompressed)
2019-07-19T05:07:46.450Z,1563512866.450 [ESPComponent](INFO): console:
   Data Size:    1681388 Bytes =  1.6 MB
2019-07-19T05:07:46.854Z,1563512866.854 [ESPComponent](INFO): console:
   Load Address: 30008000
2019-07-19T05:07:47.258Z,1563512867.258 [ESPComponent](INFO): console:
   Entry Point:  30008000
2019-07-19T05:07:47.662Z,1563512867.662 [ESPComponent](INFO): console:
   Verifying Checksum ... OK
2019-07-19T05:07:48.066Z,1563512868.066 [ESPComponent](INFO): console:
   Loading Kernel Image ... OK
2019-07-19T05:07:48.470Z,1563512868.470 [ESPComponent](INFO): console:
OK
2019-07-19T05:07:48.873Z,1563512868.873 [ESPComponent](INFO): console:

2019-07-19T05:07:49.278Z,1563512869.278 [ESPComponent](INFO): console:
Starting kernel ...
2019-07-19T05:07:49.682Z,1563512869.682 [ESPComponent](INFO): console:

2019-07-19T05:07:50.086Z,1563512870.086 [ESPComponent](INFO): console:
Uncompressing Linux... done, booting the kernel.
2019-07-19T05:07:50.491Z,1563512870.491 [ESPComponent](INFO): console:
[    0.00] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=00053177
2019-07-19T05:07:50.895Z,1563512870.895 [ESPComponent](INFO): console:
[    0.00] CPU: VIVT data cache, VIVT instruction cache
2019-07-19T05:07:51.298Z,1563512871.298 [ESPComponent](INFO): console:
[    0.00] Machine: NXP EA313X
2019-07-19T05:07:51.702Z,1563512871.702 [ESPComponent](INFO): console:
[    0.00] Memory policy: ECC disabled, Data cache writeback
2019-07-19T05:07:52.107Z,1563512872.107 [ESPComponent](INFO): console:
[    0.00] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 16256
2019-07-19T05:07:52.510Z,1563512872.510 [ESPComponent](INFO): console:
[    0.24] Mount-cache hash table entries: 512
2019-07-19T05:07:52.914Z,1563512872.914 [ESPComponent](INFO): console:
[    0.29] MBARI ESP 3G
2019-07-19T05:07:52.947Z,1563512872.947 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:07:52.948Z,1563512872.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 92
2019-07-19T05:07:52.948Z,1563512872.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:07:52.959Z,1563512872.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:07:53.319Z,1563512873.319 [ESPComponent](INFO): console:
[    0.39] bio: create slab <bio-0> at 0
2019-07-19T05:07:53.360Z,1563512873.360 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:07:53.360Z,1563512873.360 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 92
2019-07-19T05:07:53.723Z,1563512873.723 [ESPComponent](INFO): console:
[    0.46] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T05:07:54.128Z,1563512874.128 [ESPComponent](INFO): console:
[    0.48] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T05:07:54.531Z,1563512874.531 [ESPComponent](INFO): console:
[    0.50] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T05:07:54.935Z,1563512874.935 [ESPComponent](INFO): console:
[] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T05:07:55.339Z,1563512875.339 [ESPComponent](INFO): console:
[    0.54] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T05:07:55.743Z,1563512875.743 [ESPComponent](INFO): console:
[    0.56] i2c i2c-0: Master timed out. stat = 2a00, cntrl = 000e. Resetting master...
2019-07-19T05:07:56.147Z,1563512876.147 [ESPComponent](INFO): console:
[    0.56] NetWinder Floating Point Emulator V0.97 (double precision)
2019-07-19T05:07:56.550Z,1563512876.550 [ESPComponent](INFO): console:
[    0.58] yaffs Dec 14 2018 09:19:05 Installing. 
2019-07-19T05:07:56.955Z,1563512876.955 [ESPComponent](INFO): console:
[    0.72] m25p80 spi0.3: m25p80 found s25sl032a flash
2019-07-19T05:07:57.359Z,1563512877.359 [ESPComponent](INFO): console:
[    2.55] VFS: Mounted root (ext2 filesystem) readonly on device 179:2.
2019-07-19T05:07:57.761Z,1563512877.761 [ESPComponent](INFO): console:

2019-07-19T05:07:58.166Z,1563512878.166 [ESPComponent](INFO): console:INIT: version 2.85 booting
2019-07-19T05:07:58.570Z,1563512878.570 [ESPComponent](INFO): console:

2019-07-19T05:07:58.974Z,1563512878.974 [ESPComponent](INFO): console:MBARIedded Linux://www.g   mailto:brent@mbari.org
2019-07-19T05:07:59.378Z,1563512879.378 [ESPComponent](INFO): console:
ESPhonu
2019-07-19T05:07:59.781Z,1563512879.781 [ESPComponent](INFO): console:

2019-07-19T05:08:00.186Z,1563512880.186 [ESPComponent](INFO): console:INIT: Entering runlevel: 3
2019-07-19T05:08:00.590Z,1563512880.590 [ESPComponent](INFO): console:

2019-07-19T05:08:00.994Z,1563512880.994 [ESPComponent](INFO): console:Loading kernel modules
2019-07-19T05:08:01.398Z,1563512881.398 [ESPComponent](INFO): console:
Mounting filesystems and adding swapfiles listed in /etc/fstab
2019-07-19T05:08:01.803Z,1563512881.803 [ESPComponent](INFO): console:
mount: /dev/mmcblk0p2 already mounted or /card busy
2019-07-19T05:08:02.206Z,1563512882.206 [ESPComponent](INFO): console:
Starting hotplug events dispatcher udevd
2019-07-19T05:08:02.610Z,1563512882.610 [ESPComponent](INFO): console:
Set system clock to:  Thu Jul 18 22:04:48 PDT 2019
2019-07-19T05:08:03.014Z,1563512883.014 [ESPComponent](INFO): console:
Starting syslogd klogd...
2019-07-19T05:08:03.418Z,1563512883.418 [ESPComponent](INFO): console:
Bringing up interface lo ...
2019-07-19T05:08:03.822Z,1563512883.822 [ESPComponent](INFO): console:
lo IP=127.0.0.1
2019-07-19T05:08:04.226Z,1563512884.226 [ESPComponent](INFO): console:
Packet forwarding enabled
2019-07-19T05:08:04.630Z,1563512884.630 [ESPComponent](INFO): console:
Bringing up interface ppp2 ...
2019-07-19T05:08:05.034Z,1563512885.034 [ESPComponent](INFO): console:
Bringing up interface eth0 ...
2019-07-19T05:08:05.439Z,1563512885.439 [ESPComponent](INFO): console:
Determining IP configuration for eth0....udhcpc (v1.11.2) started
2019-07-19T05:08:05.842Z,1563512885.842 [ESPComponent](INFO): console:
Sending discover...
2019-07-19T05:08:06.246Z,1563512886.246 [ESPComponent](INFO): console:
Sending discover...
2019-07-19T05:08:06.721Z,1563512886.721 [ESPComponent](INFO): console:

2019-07-19T05:08:07.054Z,1563512887.054 [ESPComponent](INFO): console:Sending discover...
2019-07-19T05:08:07.529Z,1563512887.529 [ESPComponent](INFO): console:

2019-07-19T05:08:10.286Z,1563512890.286 [ESPComponent](INFO): console:No lease, forking to background
2019-07-19T05:08:10.690Z,1563512890.690 [ESPComponent](INFO): console:
Bringing up interface eth0:10 ...
2019-07-19T05:08:11.094Z,1563512891.094 [ESPComponent](INFO): console:
eth0:10 IP=10.10.10.10
2019-07-19T05:08:11.498Z,1563512891.498 [ESPComponent](INFO): console:
Bringing up interface wlan0 ...
2019-07-19T05:08:11.902Z,1563512891.902 [ESPComponent](INFO): console:
Successfully initialized wpa_supplicant
2019-07-19T05:08:12.377Z,1563512892.377 [ESPComponent](INFO): console:

2019-07-19T05:08:15.134Z,1563512895.134 [ESPComponent](INFO): console:rfkill: Cannot open RFKILL control device
2019-07-19T05:08:15.609Z,1563512895.609 [ESPComponent](INFO): console:

2019-07-19T05:08:18.771Z,1563512898.771 [ESPComponent](INFO): console:Determining IP configuration for wlan0....udhcpc (v1.11.2) started
2019-07-19T05:08:19.245Z,1563512899.245 [ESPComponent](INFO): console:

2019-07-19T05:08:21.194Z,1563512901.194 [ESPComponent](INFO): console:Sending discover...
2019-07-19T05:08:21.669Z,1563512901.669 [ESPComponent](INFO): console:

2019-07-19T05:08:23.213Z,1563512903.213 [ESPComponent](IMPORTANT): ESP has connected as client
2019-07-19T05:08:24.024Z,1563512904.024 [ESPComponent](IMPORTANT): [sample #3] Setting cartridge argument to -4
2019-07-19T05:08:24.025Z,1563512904.025 [ESPComponent](IMPORTANT): [sample #3] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
2019-07-19T05:08:24.025Z,1563512904.025 [ESPComponent](DEBUG): Writing samplingActive=1, sampleNumber=3
2019-07-19T05:08:24.060Z,1563512904.060 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Stopped
2019-07-19T05:08:24.060Z,1563512904.060 [sample:SampleAtDepth:SampleWrapper:SampleESP:C] Running Loop=1
2019-07-19T05:08:24.464Z,1563512904.464 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 7.000000 m inwater withchlorophyll fluorescence.
2019-07-19T05:08:24.464Z,1563512904.464 [sample:SampleAtDepth:SampleWrapper:SampleESP:C] Stopped
2019-07-19T05:08:24.464Z,1563512904.464 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP] Running Loop=1
2019-07-19T05:08:24.830Z,1563512904.830 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_PREPARING_SHOW_LOG
2019-07-19T05:08:25.234Z,1563512905.234 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_PREPARING_SHOW_STATUS
2019-07-19T05:08:27.254Z,1563512907.254 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_LOADING_CARTRIDGE
2019-07-19T05:09:05.634Z,1563512945.634 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_WAITING_FOR_PRIMING (@40,archiveHiBiomass_bac)
2019-07-19T05:09:07.250Z,1563512947.250 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_PRIMING (@100.0)
2019-07-19T05:09:08.058Z,1563512948.058 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_WAITING_FOR_FILTERING (@100.0)
2019-07-19T05:09:54.141Z,1563512994.141 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:09:54.141Z,1563512994.141 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 93
2019-07-19T05:09:54.141Z,1563512994.141 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:09:54.153Z,1563512994.153 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:09:54.552Z,1563512994.552 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:09:54.552Z,1563512994.552 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 93
2019-07-19T05:11:36.730Z,1563513096.730 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_FILTERING (@0.0)
2019-07-19T05:11:37.134Z,1563513097.134 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
2019-07-19T05:11:55.341Z,1563513115.341 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:11:55.341Z,1563513115.341 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 94
2019-07-19T05:11:55.341Z,1563513115.341 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:11:55.378Z,1563513115.378 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:11:55.750Z,1563513115.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:11:55.750Z,1563513115.750 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 94
2019-07-19T05:12:31.705Z,1563513151.705 [sample](INFO): Timed out from 2019-07-19T03:42:31.5Z
2019-07-19T05:12:31.706Z,1563513151.706 [MissionManager](INFO): sample is completed.
2019-07-19T05:12:31.706Z,1563513151.706 [MissionManager](INFO): Uninitializing Mission sample
2019-07-19T05:12:31.706Z,1563513151.706 [sample] Stopped
2019-07-19T05:12:31.706Z,1563513151.706 [sample](DEBUG): Aggregate::uninitialize sample
2019-07-19T05:12:31.706Z,1563513151.706 [sample:A.AbortSample] Stopped
2019-07-19T05:12:31.706Z,1563513151.706 [sample:A.AbortSample](INFO): Uninitializing AbortSample.
2019-07-19T05:12:31.706Z,1563513151.706 [sample:B.Pitch] Stopped
2019-07-19T05:12:31.706Z,1563513151.706 [sample:C.SetSpeed] Stopped
2019-07-19T05:12:31.706Z,1563513151.706 [sample:C.SetSpeed](DEBUG): Uninitialize.
2019-07-19T05:12:31.706Z,1563513151.706 [sample:SampleAtDepth] Stopped
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleAtDepth](DEBUG): Aggregate::uninitialize sample:SampleAtDepth
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleAtDepth:SampleWrapper] Stopped
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleAtDepth:SampleWrapper:A.Pitch] Stopped
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleAtDepth:SampleWrapper:SampleESP] Stopped
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleESP
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP] Stopped
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleRepeater] Stopped
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleRepeater](DEBUG): Aggregate::uninitialize sample:SampleRepeater
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleRepeater:Sample] Stopped
2019-07-19T05:12:31.707Z,1563513151.707 [sample:SampleRepeater:Sample](DEBUG): Aggregate::uninitialize sample:SampleRepeater:Sample
2019-07-19T05:12:32.078Z,1563513152.078 [ESPComponent](DEBUG): Resetting stopSampling to false
2019-07-19T05:12:32.078Z,1563513152.078 [ESPComponent](IMPORTANT): [sample #3] Stop sampling requested.
2019-07-19T05:12:32.126Z,1563513152.126 [MissionManager](IMPORTANT): Started mission Default
2019-07-19T05:12:32.126Z,1563513152.126 [Default] Running Loop=1
2019-07-19T05:12:32.126Z,1563513152.126 [Default](DEBUG): Aggregate::initialize Default
2019-07-19T05:12:32.126Z,1563513152.126 [Default:B.GoToSurface] Running Loop=1
2019-07-19T05:12:32.126Z,1563513152.126 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-19T05:12:32.127Z,1563513152.127 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-19T05:12:32.127Z,1563513152.127 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-19T05:12:32.127Z,1563513152.127 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-19T05:12:32.128Z,1563513152.128 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-19T05:12:32.128Z,1563513152.128 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-19T05:12:32.128Z,1563513152.128 [Default:A.Wait] Running Loop=1
2019-07-19T05:12:32.128Z,1563513152.128 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-19T05:12:32.481Z,1563513152.481 [ESPComponent](IMPORTANT): Stop sampling requested. Issuing Cmd.pauseFiltering
2019-07-19T05:12:32.693Z,1563513152.693 [Radio_Surface](INFO): Powering up
2019-07-19T05:12:38.085Z,1563513158.085 [DataOverHttps](INFO): Radio surface powered ON.
2019-07-19T05:12:38.586Z,1563513158.586 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_PAUSED (@25.041)
2019-07-19T05:12:38.586Z,1563513158.586 [ESPComponent](DEBUG): Writing sampleVolume=25.041000
2019-07-19T05:12:38.990Z,1563513158.990 [ESPComponent](IMPORTANT): In S_PAUSED received result: 25.041

2019-07-19T05:12:39.394Z,1563513159.394 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_PROCESSING (@25.041)
2019-07-19T05:12:41.818Z,1563513161.818 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_WAITING_FOR_PROCESSED (@40,archiveHiBiomass_bac)
2019-07-19T05:12:45.488Z,1563513165.488 [Default:A.Wait](INFO): Done Waiting.
2019-07-19T05:12:45.488Z,1563513165.488 [Default:A.Wait] Stopped
2019-07-19T05:12:45.488Z,1563513165.488 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T05:13:08.385Z,1563513188.385 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-19T05:13:39.089Z,1563513219.089 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-19T05:13:56.193Z,1563513236.193 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:13:56.193Z,1563513236.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 95
2019-07-19T05:13:56.193Z,1563513236.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:13:56.229Z,1563513236.229 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:13:56.637Z,1563513236.637 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:13:56.637Z,1563513236.637 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 95
2019-07-19T05:14:09.793Z,1563513249.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-19T05:14:40.497Z,1563513280.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-19T05:15:09.263Z,1563513309.263 [CommandLine](IMPORTANT): got command show stack
2019-07-19T05:15:09.263Z,1563513309.263 [CommandLine](IMPORTANT): Behavior Stack:

2019-07-19T05:15:09.263Z,1563513309.263 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-07-19T05:15:11.201Z,1563513311.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-19T05:15:17.742Z,1563513317.742 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003892
2019-07-19T05:15:23.395Z,1563513323.395 [CommandLine](IMPORTANT): got command maintain clear
2019-07-19T05:15:23.498Z,1563513323.498 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-19T05:15:23.859Z,1563513323.859 [Default:CheckIn] Running Loop=1
2019-07-19T05:15:23.859Z,1563513323.859 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T05:15:23.860Z,1563513323.860 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T05:15:25.446Z,1563513325.446 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-19T05:15:25.447Z,1563513325.447 [NAL9602] Data Fault, FailCount= 4
2019-07-19T05:15:25.447Z,1563513325.447 [NAL9602](ERROR): Data Fault
2019-07-19T05:15:25.865Z,1563513325.865 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T05:15:25.878Z,1563513325.878 [NAL9602](INFO): Powering down
2019-07-19T05:15:26.761Z,1563513326.761 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T05:15:26.761Z,1563513326.761 [NAL9602] No Fault, FailCount= 4
2019-07-19T05:15:56.182Z,1563513356.182 [NAL9602](INFO): Powering up NAL9602
2019-07-19T05:15:57.024Z,1563513357.024 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:15:57.024Z,1563513357.024 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 96
2019-07-19T05:15:57.024Z,1563513357.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:15:57.043Z,1563513357.043 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:15:57.425Z,1563513357.425 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:15:57.426Z,1563513357.426 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 96
2019-07-19T05:16:07.086Z,1563513367.086 [NAL9602](INFO): NAL9602 initialized
2019-07-19T05:16:07.909Z,1563513367.909 [NAL9602](DEBUG): Fix Requested
2019-07-19T05:17:58.219Z,1563513478.219 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:17:58.220Z,1563513478.220 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 97
2019-07-19T05:17:58.220Z,1563513478.220 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:17:58.230Z,1563513478.230 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:17:58.637Z,1563513478.637 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:17:58.637Z,1563513478.637 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 97
2019-07-19T05:18:42.923Z,1563513522.923 [CommandLine](IMPORTANT): got command get depth
2019-07-19T05:18:42.923Z,1563513522.923 [CommandLine](IMPORTANT): depth 0.026628 m
2019-07-19T05:18:52.324Z,1563513532.324 [NAL9602](INFO): SBD MO Status=2, MOMSN=29927, MT Status=2, MTMSN=0
2019-07-19T05:18:52.324Z,1563513532.324 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-19T05:19:59.411Z,1563513599.411 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:19:59.411Z,1563513599.411 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 98
2019-07-19T05:19:59.412Z,1563513599.412 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:19:59.422Z,1563513599.422 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:19:59.833Z,1563513599.833 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:19:59.833Z,1563513599.833 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 98
2019-07-19T05:20:24.052Z,1563513624.052 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T05:15:23.9Z
2019-07-19T05:20:24.053Z,1563513624.053 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T05:20:24.053Z,1563513624.053 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T05:20:32.352Z,1563513632.352 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20190719T031420/Courier0006.lzma
2019-07-19T05:20:33.158Z,1563513633.158 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Courier0006.lzma.bak
2019-07-19T05:20:33.158Z,1563513633.158 [DataOverHttps](INFO): SBD MOMSN=11455923
2019-07-19T05:20:50.868Z,1563513650.868 [DataOverHttps](INFO): Sending 2258 bytes from file Logs/20190719T031420/Express0007.lzma
2019-07-19T05:20:51.674Z,1563513651.674 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Express0007.lzma.bak
2019-07-19T05:20:51.674Z,1563513651.674 [DataOverHttps](INFO): SBD MOMSN=11455925
2019-07-19T05:20:56.411Z,1563513656.411 [Default:CheckIn:Read_Iridium] Stopped
2019-07-19T05:20:56.411Z,1563513656.411 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-19T05:20:56.411Z,1563513656.411 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T05:21:10.114Z,1563513670.114 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T05:21:40.818Z,1563513700.818 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-19T05:22:00.244Z,1563513720.244 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:22:00.244Z,1563513720.244 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 99
2019-07-19T05:22:00.244Z,1563513720.244 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:22:00.255Z,1563513720.255 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:22:00.664Z,1563513720.664 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:22:00.664Z,1563513720.664 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 99
2019-07-19T05:24:01.447Z,1563513841.447 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:24:01.447Z,1563513841.447 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 100
2019-07-19T05:24:01.447Z,1563513841.447 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:24:01.460Z,1563513841.460 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:24:01.460Z,1563513841.460 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:25:30.698Z,1563513930.698 [ESPComponent](IMPORTANT): [sample #3] ESP sampling state: S_STOPPING (@40,archiveHiBiomass_bac)
2019-07-19T05:25:56.986Z,1563513956.986 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T05:25:56.986Z,1563513956.986 [Default:CheckIn:C.Wait] Stopped
2019-07-19T05:25:56.986Z,1563513956.986 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T05:25:56.987Z,1563513956.987 [Default:CheckIn:D] Running Loop=1
2019-07-19T05:25:57.362Z,1563513957.362 [ESPComponent](IMPORTANT): [sample #3] ESP sampling sequence completed normally.
2019-07-19T05:25:57.363Z,1563513957.363 [ESPComponent](DEBUG): Writing samplingActive=0, sampleNumber=3
2019-07-19T05:25:57.393Z,1563513957.393 [Default:CheckIn:D] Stopped
2019-07-19T05:25:57.393Z,1563513957.393 [Default:CheckIn:E] Running Loop=1
2019-07-19T05:25:57.765Z,1563513957.765 [ESPComponent](DEBUG): Resetting stopSampling to false
2019-07-19T05:25:57.766Z,1563513957.766 [ESPComponent](IMPORTANT): [sample #3] ESP log summary report (2 messages):
@22:08:40.80 Selecting Cartridge 40
@22:09:23.59 Cmd::Paused in FILTERING --  during Sample Pump (SP) move after sampling 25.041ml

2019-07-19T05:25:57.766Z,1563513957.766 [ESPComponent](INFO): powering down ESP
2019-07-19T05:25:57.767Z,1563513957.767 [ESPComponent](INFO): powering down ESP secondary power supply
2019-07-19T05:25:57.958Z,1563513957.958 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.421116 min 
2019-07-19T05:25:57.958Z,1563513957.958 [Default:CheckIn:E] Stopped
2019-07-19T05:25:57.958Z,1563513957.958 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T05:25:57.958Z,1563513957.958 [Default:CheckIn] Stopped
2019-07-19T05:25:57.958Z,1563513957.958 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T05:25:57.959Z,1563513957.959 [Default:CheckIn](INFO): Running loop #2
2019-07-19T05:25:57.959Z,1563513957.959 [Default:CheckIn] Running Loop=2
2019-07-19T05:25:57.959Z,1563513957.959 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T05:25:57.959Z,1563513957.959 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T05:25:59.405Z,1563513959.405 [NAL9602](DEBUG): Fix Requested
2019-07-19T05:26:30.499Z,1563513990.499 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-07-19T05:26:30.573Z,1563513990.573 [NAL9602](FAULT): received: 
+CSQ:0

OK927, 2, 0, 0, 0

OK
2019-07-19T05:26:30.573Z,1563513990.573 [NAL9602] Data Fault, FailCount= 5
2019-07-19T05:26:30.573Z,1563513990.573 [NAL9602](ERROR): Data Fault
2019-07-19T05:26:30.606Z,1563513990.606 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T05:26:30.606Z,1563513990.606 [CBIT](CRITICAL): Data Fault in component: NAL9602
2019-07-19T05:26:30.906Z,1563513990.906 [NAL9602](INFO): Powering down
2019-07-19T05:26:30.933Z,1563513990.933 [CBIT](INFO): Critical error at 20190719T052630
2019-07-19T05:26:30.933Z,1563513990.933 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-07-19T05:28:30.938Z,1563514110.938 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T05:28:30.938Z,1563514110.938 [NAL9602] No Fault, FailCount= 5
2019-07-19T05:28:31.294Z,1563514111.294 [NAL9602](INFO): Powering up NAL9602
2019-07-19T05:28:42.202Z,1563514122.202 [NAL9602](INFO): NAL9602 initialized
2019-07-19T05:28:43.004Z,1563514123.004 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-19T05:28:43.004Z,1563514123.004 [NAL9602] Data Fault, FailCount= 1
2019-07-19T05:28:43.004Z,1563514123.004 [NAL9602](ERROR): Data Fault
2019-07-19T05:28:43.032Z,1563514123.032 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T05:28:43.414Z,1563514123.414 [NAL9602](INFO): Powering down
2019-07-19T05:28:44.251Z,1563514124.251 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T05:28:44.251Z,1563514124.251 [NAL9602] No Fault, FailCount= 1
2019-07-19T05:29:13.715Z,1563514153.715 [NAL9602](INFO): Powering up NAL9602
2019-07-19T05:29:24.624Z,1563514164.624 [NAL9602](INFO): NAL9602 initialized
2019-07-19T05:29:25.437Z,1563514165.437 [NAL9602](DEBUG): Fix Requested
2019-07-19T05:30:57.985Z,1563514257.985 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T05:25:57.0Z
2019-07-19T05:30:57.985Z,1563514257.985 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T05:30:57.985Z,1563514257.985 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T05:31:01.999Z,1563514261.999 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T05:31:06.012Z,1563514266.012 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20190719T031420/Courier0009.lzma
2019-07-19T05:31:06.818Z,1563514266.818 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Courier0009.lzma.bak
2019-07-19T05:31:06.818Z,1563514266.818 [DataOverHttps](INFO): SBD MOMSN=11456064
2019-07-19T05:31:24.753Z,1563514284.753 [DataOverHttps](INFO): Sending 609 bytes from file Logs/20190719T031420/Express0010.lzma
2019-07-19T05:31:25.558Z,1563514285.558 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Express0010.lzma.bak
2019-07-19T05:31:25.559Z,1563514285.559 [DataOverHttps](INFO): SBD MOMSN=11456067
2019-07-19T05:31:30.312Z,1563514290.312 [Default:CheckIn:Read_Iridium] Stopped
2019-07-19T05:31:30.312Z,1563514290.312 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-19T05:31:30.312Z,1563514290.312 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T05:31:32.702Z,1563514292.702 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-19T05:36:30.886Z,1563514590.886 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T05:36:30.886Z,1563514590.886 [Default:CheckIn:C.Wait] Stopped
2019-07-19T05:36:30.886Z,1563514590.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T05:36:30.886Z,1563514590.886 [Default:CheckIn:D] Running Loop=1
2019-07-19T05:36:31.289Z,1563514591.289 [Default:CheckIn:D] Stopped
2019-07-19T05:36:31.289Z,1563514591.289 [Default:CheckIn:E] Running Loop=1
2019-07-19T05:36:31.695Z,1563514591.695 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.986047 min 
2019-07-19T05:36:31.695Z,1563514591.695 [Default:CheckIn:E] Stopped
2019-07-19T05:36:31.695Z,1563514591.695 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T05:36:31.695Z,1563514591.695 [Default:CheckIn] Stopped
2019-07-19T05:36:31.696Z,1563514591.696 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T05:36:31.696Z,1563514591.696 [Default:CheckIn](INFO): Running loop #3
2019-07-19T05:36:31.696Z,1563514591.696 [Default:CheckIn] Running Loop=3
2019-07-19T05:36:31.696Z,1563514591.696 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T05:36:31.696Z,1563514591.696 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T05:36:33.297Z,1563514593.297 [NAL9602](DEBUG): Fix Requested
2019-07-19T05:37:04.399Z,1563514624.399 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-07-19T05:37:04.473Z,1563514624.473 [NAL9602](FAULT): received: 
+CSQ:0

OK927, 2, 0, 0, 0

OK
2019-07-19T05:37:04.473Z,1563514624.473 [NAL9602] Data Fault, FailCount= 2
2019-07-19T05:37:04.473Z,1563514624.473 [NAL9602](ERROR): Data Fault
2019-07-19T05:37:04.537Z,1563514624.537 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T05:37:04.797Z,1563514624.797 [NAL9602](INFO): Powering down
2019-07-19T05:37:05.642Z,1563514625.642 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T05:37:05.642Z,1563514625.642 [NAL9602] No Fault, FailCount= 2
2019-07-19T05:37:35.095Z,1563514655.095 [NAL9602](INFO): Powering up NAL9602
2019-07-19T05:37:46.010Z,1563514666.010 [NAL9602](INFO): NAL9602 initialized
2019-07-19T05:37:46.829Z,1563514666.829 [NAL9602](DEBUG): Fix Requested
2019-07-19T05:39:01.590Z,1563514741.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:39:01.590Z,1563514741.590 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 100
2019-07-19T05:41:02.380Z,1563514862.380 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:41:02.380Z,1563514862.380 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-07-19T05:41:02.380Z,1563514862.380 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:41:02.391Z,1563514862.391 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:41:02.809Z,1563514862.809 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:41:02.809Z,1563514862.809 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-07-19T05:41:31.871Z,1563514891.871 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T05:36:31.7Z
2019-07-19T05:41:31.871Z,1563514891.871 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T05:41:31.872Z,1563514891.872 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T05:41:35.891Z,1563514895.891 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T05:41:40.020Z,1563514900.020 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190719T031420/Courier0012.lzma
2019-07-19T05:41:40.826Z,1563514900.826 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Courier0012.lzma.bak
2019-07-19T05:41:40.826Z,1563514900.826 [DataOverHttps](INFO): SBD MOMSN=11456079
2019-07-19T05:41:58.501Z,1563514918.501 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20190719T031420/Express0013.lzma
2019-07-19T05:41:59.306Z,1563514919.306 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Express0013.lzma.bak
2019-07-19T05:41:59.307Z,1563514919.307 [DataOverHttps](INFO): SBD MOMSN=11456081
2019-07-19T05:42:04.207Z,1563514924.207 [Default:CheckIn:Read_Iridium] Stopped
2019-07-19T05:42:04.207Z,1563514924.207 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-19T05:42:04.207Z,1563514924.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T05:42:06.593Z,1563514926.593 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-19T05:43:03.570Z,1563514983.570 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:43:03.570Z,1563514983.570 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-07-19T05:43:03.570Z,1563514983.570 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:43:03.581Z,1563514983.581 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:43:03.997Z,1563514983.997 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:43:03.997Z,1563514983.997 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-07-19T05:45:04.781Z,1563515104.781 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:45:04.781Z,1563515104.781 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-07-19T05:45:04.781Z,1563515104.781 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:45:04.792Z,1563515104.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:45:05.197Z,1563515105.197 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:45:05.197Z,1563515105.197 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-07-19T05:47:04.799Z,1563515224.799 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T05:47:04.800Z,1563515224.800 [Default:CheckIn:C.Wait] Stopped
2019-07-19T05:47:04.800Z,1563515224.800 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T05:47:04.800Z,1563515224.800 [Default:CheckIn:D] Running Loop=1
2019-07-19T05:47:05.206Z,1563515225.206 [Default:CheckIn:D] Stopped
2019-07-19T05:47:05.206Z,1563515225.206 [Default:CheckIn:E] Running Loop=1
2019-07-19T05:47:05.605Z,1563515225.605 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:47:05.605Z,1563515225.605 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-07-19T05:47:05.605Z,1563515225.605 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:47:05.608Z,1563515225.608 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.551335 min 
2019-07-19T05:47:05.608Z,1563515225.608 [Default:CheckIn:E] Stopped
2019-07-19T05:47:05.608Z,1563515225.608 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T05:47:05.608Z,1563515225.608 [Default:CheckIn] Stopped
2019-07-19T05:47:05.608Z,1563515225.608 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T05:47:05.608Z,1563515225.608 [Default:CheckIn](INFO): Running loop #4
2019-07-19T05:47:05.608Z,1563515225.608 [Default:CheckIn] Running Loop=4
2019-07-19T05:47:05.609Z,1563515225.609 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T05:47:05.609Z,1563515225.609 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T05:47:05.617Z,1563515225.617 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:47:06.016Z,1563515226.016 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:47:06.016Z,1563515226.016 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-07-19T05:47:07.202Z,1563515227.202 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-19T05:47:07.203Z,1563515227.203 [NAL9602] Data Fault, FailCount= 3
2019-07-19T05:47:07.203Z,1563515227.203 [NAL9602](ERROR): Data Fault
2019-07-19T05:47:07.253Z,1563515227.253 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T05:47:07.606Z,1563515227.606 [NAL9602](INFO): Powering down
2019-07-19T05:47:08.436Z,1563515228.436 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T05:47:08.436Z,1563515228.436 [NAL9602] No Fault, FailCount= 3
2019-07-19T05:47:37.910Z,1563515257.910 [NAL9602](INFO): Powering up NAL9602
2019-07-19T05:47:48.822Z,1563515268.822 [NAL9602](INFO): NAL9602 initialized
2019-07-19T05:47:49.633Z,1563515269.633 [NAL9602](DEBUG): Fix Requested
2019-07-19T05:49:06.813Z,1563515346.813 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:49:06.813Z,1563515346.813 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-07-19T05:49:06.813Z,1563515346.813 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:49:06.824Z,1563515346.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:49:07.223Z,1563515347.223 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:49:07.223Z,1563515347.223 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-07-19T05:51:08.015Z,1563515468.015 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:51:08.015Z,1563515468.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-07-19T05:51:08.015Z,1563515468.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:51:08.025Z,1563515468.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:51:08.428Z,1563515468.428 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:51:08.428Z,1563515468.428 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-07-19T05:52:05.787Z,1563515525.787 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T05:47:05.6Z
2019-07-19T05:52:05.787Z,1563515525.787 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T05:52:05.788Z,1563515525.788 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T05:52:16.432Z,1563515536.432 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190719T031420/Courier0015.lzma
2019-07-19T05:52:17.238Z,1563515537.238 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Courier0015.lzma.bak
2019-07-19T05:52:17.239Z,1563515537.239 [DataOverHttps](INFO): SBD MOMSN=11456087
2019-07-19T05:52:32.345Z,1563515552.345 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20190719T031420/Express0016.lzma
2019-07-19T05:52:33.150Z,1563515553.150 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Express0016.lzma.bak
2019-07-19T05:52:33.151Z,1563515553.151 [DataOverHttps](INFO): SBD MOMSN=11456089
2019-07-19T05:52:38.119Z,1563515558.119 [Default:CheckIn:Read_Iridium] Stopped
2019-07-19T05:52:38.119Z,1563515558.119 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-19T05:52:38.119Z,1563515558.119 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T05:52:51.826Z,1563515571.826 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T05:53:08.840Z,1563515588.840 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:53:08.840Z,1563515588.840 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-07-19T05:53:08.840Z,1563515588.840 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:53:08.851Z,1563515588.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:53:09.270Z,1563515589.270 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:53:09.270Z,1563515589.270 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-07-19T05:53:22.578Z,1563515602.578 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-19T05:55:09.651Z,1563515709.651 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:55:09.652Z,1563515709.652 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-07-19T05:55:09.652Z,1563515709.652 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:55:09.662Z,1563515709.662 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:55:10.077Z,1563515710.077 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:55:10.077Z,1563515710.077 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-07-19T05:57:10.862Z,1563515830.862 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:57:10.862Z,1563515830.862 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-07-19T05:57:10.862Z,1563515830.862 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:57:10.873Z,1563515830.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:57:11.278Z,1563515831.278 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:57:11.278Z,1563515831.278 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-07-19T05:57:38.735Z,1563515858.735 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T05:57:38.735Z,1563515858.735 [Default:CheckIn:C.Wait] Stopped
2019-07-19T05:57:38.735Z,1563515858.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T05:57:38.736Z,1563515858.736 [Default:CheckIn:D] Running Loop=1
2019-07-19T05:57:39.139Z,1563515859.139 [Default:CheckIn:D] Stopped
2019-07-19T05:57:39.139Z,1563515859.139 [Default:CheckIn:E] Running Loop=1
2019-07-19T05:57:39.543Z,1563515859.543 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.116882 min 
2019-07-19T05:57:39.543Z,1563515859.543 [Default:CheckIn:E] Stopped
2019-07-19T05:57:39.543Z,1563515859.543 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T05:57:39.543Z,1563515859.543 [Default:CheckIn] Stopped
2019-07-19T05:57:39.544Z,1563515859.544 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T05:57:39.544Z,1563515859.544 [Default:CheckIn](INFO): Running loop #5
2019-07-19T05:57:39.544Z,1563515859.544 [Default:CheckIn] Running Loop=5
2019-07-19T05:57:39.544Z,1563515859.544 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T05:57:39.544Z,1563515859.544 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T05:57:41.153Z,1563515861.153 [NAL9602](DEBUG): Fix Requested
2019-07-19T05:59:12.069Z,1563515952.069 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T05:59:12.069Z,1563515952.069 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-07-19T05:59:12.069Z,1563515952.069 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T05:59:12.080Z,1563515952.080 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T05:59:12.473Z,1563515952.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T05:59:12.473Z,1563515952.473 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-07-19T06:01:12.872Z,1563516072.872 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T06:01:12.872Z,1563516072.872 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-07-19T06:01:12.872Z,1563516072.872 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T06:01:12.900Z,1563516072.900 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T06:01:13.277Z,1563516073.277 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T06:01:13.277Z,1563516073.277 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-07-19T06:02:39.721Z,1563516159.721 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T05:57:39.5Z
2019-07-19T06:02:39.721Z,1563516159.721 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T06:02:39.721Z,1563516159.721 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T06:02:43.735Z,1563516163.735 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T06:02:47.800Z,1563516167.800 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190719T031420/Courier0018.lzma
2019-07-19T06:02:48.606Z,1563516168.606 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Courier0018.lzma.bak
2019-07-19T06:02:48.607Z,1563516168.607 [DataOverHttps](INFO): SBD MOMSN=11456099
2019-07-19T06:03:05.356Z,1563516185.356 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20190719T031420/Express0019.lzma
2019-07-19T06:03:06.158Z,1563516186.158 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Express0019.lzma.bak
2019-07-19T06:03:06.159Z,1563516186.159 [DataOverHttps](INFO): SBD MOMSN=11456101
2019-07-19T06:03:10.839Z,1563516190.839 [Default:CheckIn:Read_Iridium] Stopped
2019-07-19T06:03:10.839Z,1563516190.839 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-19T06:03:10.839Z,1563516190.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T06:03:13.676Z,1563516193.676 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T06:03:13.676Z,1563516193.676 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-07-19T06:03:13.676Z,1563516193.676 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T06:03:13.698Z,1563516193.698 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T06:03:14.081Z,1563516194.081 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T06:03:14.081Z,1563516194.081 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-07-19T06:03:14.437Z,1563516194.437 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-19T06:05:14.849Z,1563516314.849 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T06:05:14.849Z,1563516314.849 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-07-19T06:05:14.849Z,1563516314.849 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T06:05:14.860Z,1563516314.860 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T06:05:15.265Z,1563516315.265 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T06:05:15.265Z,1563516315.265 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-07-19T06:07:16.050Z,1563516436.050 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T06:07:16.051Z,1563516436.051 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-07-19T06:07:16.051Z,1563516436.051 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T06:07:16.061Z,1563516436.061 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T06:07:16.469Z,1563516436.469 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T06:07:16.469Z,1563516436.469 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-07-19T06:08:11.392Z,1563516491.392 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T06:08:11.392Z,1563516491.392 [Default:CheckIn:C.Wait] Stopped
2019-07-19T06:08:11.392Z,1563516491.392 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T06:08:11.392Z,1563516491.392 [Default:CheckIn:D] Running Loop=1
2019-07-19T06:08:11.806Z,1563516491.806 [Default:CheckIn:D] Stopped
2019-07-19T06:08:11.806Z,1563516491.806 [Default:CheckIn:E] Running Loop=1
2019-07-19T06:08:12.204Z,1563516492.204 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.661336 min 
2019-07-19T06:08:12.205Z,1563516492.205 [Default:CheckIn:E] Stopped
2019-07-19T06:08:12.205Z,1563516492.205 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T06:08:12.205Z,1563516492.205 [Default:CheckIn] Stopped
2019-07-19T06:08:12.205Z,1563516492.205 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T06:08:12.205Z,1563516492.205 [Default:CheckIn](INFO): Running loop #6
2019-07-19T06:08:12.205Z,1563516492.205 [Default:CheckIn] Running Loop=6
2019-07-19T06:08:12.205Z,1563516492.205 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T06:08:12.205Z,1563516492.205 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T06:08:13.802Z,1563516493.802 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-19T06:08:13.803Z,1563516493.803 [NAL9602] Data Fault, FailCount= 4
2019-07-19T06:08:13.803Z,1563516493.803 [NAL9602](ERROR): Data Fault
2019-07-19T06:08:13.832Z,1563516493.832 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T06:08:14.222Z,1563516494.222 [NAL9602](INFO): Powering down
2019-07-19T06:08:15.044Z,1563516495.044 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T06:08:15.044Z,1563516495.044 [NAL9602] No Fault, FailCount= 4
2019-07-19T06:08:44.506Z,1563516524.506 [NAL9602](INFO): Powering up NAL9602
2019-07-19T06:08:55.414Z,1563516535.414 [NAL9602](INFO): NAL9602 initialized
2019-07-19T06:08:56.233Z,1563516536.233 [NAL9602](DEBUG): Fix Requested
2019-07-19T06:09:16.860Z,1563516556.860 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T06:09:16.860Z,1563516556.860 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-07-19T06:09:16.860Z,1563516556.860 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T06:09:16.871Z,1563516556.871 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T06:09:17.263Z,1563516557.263 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T06:09:17.263Z,1563516557.263 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-07-19T06:11:18.080Z,1563516678.080 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T06:11:18.080Z,1563516678.080 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2019-07-19T06:11:18.080Z,1563516678.080 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T06:11:18.098Z,1563516678.098 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T06:11:18.470Z,1563516678.470 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T06:11:18.470Z,1563516678.470 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2019-07-19T06:13:12.407Z,1563516792.407 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T06:08:12.2Z
2019-07-19T06:13:12.407Z,1563516792.407 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T06:13:12.407Z,1563516792.407 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T06:13:18.952Z,1563516798.952 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T06:13:18.953Z,1563516798.953 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2019-07-19T06:13:18.953Z,1563516798.953 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T06:13:18.975Z,1563516798.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T06:13:19.290Z,1563516799.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T06:13:19.290Z,1563516799.290 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2019-07-19T06:13:21.004Z,1563516801.004 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190719T031420/Courier0021.lzma
2019-07-19T06:13:21.810Z,1563516801.810 [DataOverHttps](INFO): Moved sent file to Logs/20190719T031420/Courier0021.lzma.bak
2019-07-19T06:13:21.811Z,1563516801.811 [DataOverHttps](INFO): SBD MOMSN=11456110
2019-07-19T06:13:44.878Z,1563516824.878 [CommandLine](IMPORTANT): got command quit
2019-07-19T06:13:45.937Z,1563516825.937 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-19T06:13:45.937Z,1563516825.937 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:46.133Z,1563516826.133 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-07-19T06:13:46.133Z,1563516826.133 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:46.134Z,1563516826.134 [CommandLine](INFO): Join timeout helper Thread ID is 6381
2019-07-19T06:13:46.149Z,1563516826.149 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-07-19T06:13:46.149Z,1563516826.149 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:46.150Z,1563516826.150 [NavChartDb](INFO): Join timeout helper Thread ID is 6382
2019-07-19T06:13:46.473Z,1563516826.473 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-19T06:13:46.474Z,1563516826.474 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:46.493Z,1563516826.493 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-07-19T06:13:46.493Z,1563516826.493 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:46.494Z,1563516826.494 [Radio_Surface](INFO): Join timeout helper Thread ID is 6383
2019-07-19T06:13:46.885Z,1563516826.885 [Radio_Surface](INFO): Powering down
2019-07-19T06:13:46.886Z,1563516826.886 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-19T06:13:46.886Z,1563516826.886 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:46.897Z,1563516826.897 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-07-19T06:13:46.897Z,1563516826.897 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:46.897Z,1563516826.897 [DataOverHttps](INFO): Join timeout helper Thread ID is 6384
2019-07-19T06:13:48.208Z,1563516828.208 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-19T06:13:48.212Z,1563516828.212 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.225Z,1563516828.225 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-07-19T06:13:48.225Z,1563516828.225 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.226Z,1563516828.226 [logger](INFO): Join timeout helper Thread ID is 6385
2019-07-19T06:13:48.305Z,1563516828.305 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-19T06:13:48.305Z,1563516828.305 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.314Z,1563516828.314 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-07-19T06:13:48.314Z,1563516828.314 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.314Z,1563516828.314 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-07-19T06:13:48.314Z,1563516828.314 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.315Z,1563516828.315 [controlThread](INFO): Join timeout helper Thread ID is 6386
2019-07-19T06:13:48.368Z,1563516828.368 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-19T06:13:48.368Z,1563516828.368 [controlThread](DEBUG): Uninitializing ControlThread
2019-07-19T06:13:48.370Z,1563516828.370 [NAL9602](INFO): Powering down
2019-07-19T06:13:48.372Z,1563516828.372 [DAT](INFO): Powering down
2019-07-19T06:13:48.490Z,1563516828.490 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-07-19T06:13:48.491Z,1563516828.491 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-07-19T06:13:48.491Z,1563516828.491 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-07-19T06:13:48.492Z,1563516828.492 [MissionManager](INFO): Uninitializing Mission Default
2019-07-19T06:13:48.492Z,1563516828.492 [Default] Stopped
2019-07-19T06:13:48.492Z,1563516828.492 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-19T06:13:48.492Z,1563516828.492 [Default:B.GoToSurface] Stopped
2019-07-19T06:13:48.492Z,1563516828.492 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-19T06:13:48.492Z,1563516828.492 [Default:CheckIn] Stopped
2019-07-19T06:13:48.492Z,1563516828.492 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T06:13:48.492Z,1563516828.492 [Default:CheckIn:Read_Iridium] Stopped
2019-07-19T06:13:48.495Z,1563516828.495 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-07-19T06:13:48.495Z,1563516828.495 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-07-19T06:13:48.495Z,1563516828.495 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-07-19T06:13:48.495Z,1563516828.495 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-07-19T06:13:48.496Z,1563516828.496 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-07-19T06:13:48.496Z,1563516828.496 [BuoyancyServo](INFO): Powering down
2019-07-19T06:13:48.509Z,1563516828.509 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-07-19T06:13:48.509Z,1563516828.509 [ElevatorServo](INFO): Powering down
2019-07-19T06:13:48.510Z,1563516828.510 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-07-19T06:13:48.510Z,1563516828.510 [MassServo](INFO): Powering down
2019-07-19T06:13:48.511Z,1563516828.511 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-07-19T06:13:48.511Z,1563516828.511 [RudderServo](INFO): Powering down
2019-07-19T06:13:48.512Z,1563516828.512 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-07-19T06:13:48.512Z,1563516828.512 [ThrusterServo](INFO): Powering down
2019-07-19T06:13:48.512Z,1563516828.512 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-07-19T06:13:48.513Z,1563516828.513 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-07-19T06:13:48.513Z,1563516828.513 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-07-19T06:13:48.513Z,1563516828.513 [CBIT](DEBUG): Powering off loads.
2019-07-19T06:13:48.525Z,1563516828.525 [CBIT](DEBUG): Disabling WDT.
2019-07-19T06:13:48.537Z,1563516828.537 [CBIT](DEBUG): Opening all GF detection circuits.
2019-07-19T06:13:48.538Z,1563516828.538 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.716Z,1563516828.716 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.721Z,1563516828.721 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.769Z,1563516828.769 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-19T06:13:48.844Z,1563516828.844 [logger ThreadHandler](INFO): Thread cancelled.