2019-07-19T20:37:22.526Z,1563568642.526 [CommandLine](IMPORTANT): got command restart logs
2019-07-19T20:37:37.575Z,1563568657.575 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:37:37.575Z,1563568657.575 [WetLabsBB2FL] No Fault, FailCount= 5
2019-07-19T20:37:37.670Z,1563568657.670 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:37:37.670Z,1563568657.670 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:37:42.961Z,1563568662.961 [WetLabsBB2FL] Communications Fault, FailCount= 1
2019-07-19T20:37:42.961Z,1563568662.961 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:37:43.250Z,1563568663.250 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:37:43.461Z,1563568663.461 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:37:44.070Z,1563568664.070 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml
2019-07-19T20:37:44.071Z,1563568664.071 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
2019-07-19T20:37:44.099Z,1563568664.099 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
2019-07-19T20:37:44.101Z,1563568664.101 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
2019-07-19T20:37:44.104Z,1563568664.104 [MissionManager](INFO): DefineArg sample.CartridgeType = -1.000000 count
2019-07-19T20:37:44.107Z,1563568664.107 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count
2019-07-19T20:37:44.110Z,1563568664.110 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min
2019-07-19T20:37:44.111Z,1563568664.111 [sample:A.AbortSample](DEBUG): Construct.
2019-07-19T20:37:44.113Z,1563568664.113 [sample:B.Pitch](DEBUG): Construct.
2019-07-19T20:37:44.143Z,1563568664.143 [sample:C.SetSpeed](DEBUG): Construct.
2019-07-19T20:37:44.146Z,1563568664.146 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
2019-07-19T20:37:44.299Z,1563568664.299 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
2019-07-19T20:37:44.302Z,1563568664.302 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 10.000000 s
2019-07-19T20:37:44.313Z,1563568664.313 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
2019-07-19T20:37:44.316Z,1563568664.316 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
2019-07-19T20:37:44.319Z,1563568664.319 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool
2019-07-19T20:37:44.331Z,1563568664.331 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool
2019-07-19T20:37:44.335Z,1563568664.335 [MissionManager](INFO): DefineArg sample:SampleAtDepth.ESPCartridgeType = nan count
2019-07-19T20:37:44.346Z,1563568664.346 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2019-07-19T20:37:44.353Z,1563568664.353 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2019-07-19T20:37:44.362Z,1563568664.362 [MissionManager](INFO): DefineArg sample:SampleAtDepth.BuoyancyNeutral = 273.999991 cc
2019-07-19T20:37:44.365Z,1563568664.365 [sample:SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Construct.
2019-07-19T20:37:44.376Z,1563568664.376 [sample:SampleAtDepth:ApproachDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:37:44.383Z,1563568664.383 [sample:SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Construct.
2019-07-19T20:37:44.422Z,1563568664.422 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Construct Wait.
2019-07-19T20:37:44.455Z,1563568664.455 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:37:44.462Z,1563568664.462 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:37:44.462Z,1563568664.462 [WetLabsBB2FL] No Fault, FailCount= 1
2019-07-19T20:37:44.474Z,1563568664.474 [Reporter](INFO): VerticalControl.depthCmd 2.500000 m
2019-07-19T20:37:44.477Z,1563568664.477 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-07-19T20:37:44.477Z,1563568664.477 [MissionManager](ERROR): Slate does not contain celsius
2019-07-19T20:37:44.482Z,1563568664.482 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:37:44.513Z,1563568664.513 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-07-19T20:37:44.513Z,1563568664.513 [MissionManager](ERROR): Slate does not contain celsius
2019-07-19T20:37:44.553Z,1563568664.553 [MissionManager](DEBUG):
This mission is designed to be run in the test tank only and samples
whichever sampler is installed once the vehicle reaches the target
depth.
How long to let the mission run.
90
Depth to sample at.
7
Specifies ESP cartridge type code.
-1
Number of samples to take.
1
How long to wait between samples. This starts *before* the first sample
to exercise the SettleTime setting in the insert aggregate.
3
0
2019-07-19T20:37:44.554Z,1563568664.554 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml
2019-07-19T20:37:44.674Z,1563568664.674 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:37:44.674Z,1563568664.674 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:37:44.850Z,1563568664.850 [Reporter](INFO): ESPComponent.cartridge -9 count
2019-07-19T20:37:49.993Z,1563568669.993 [WetLabsBB2FL] Communications Fault, FailCount= 2
2019-07-19T20:37:49.993Z,1563568669.993 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:37:50.138Z,1563568670.138 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:37:50.500Z,1563568670.500 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:37:51.699Z,1563568671.699 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:37:51.699Z,1563568671.699 [WetLabsBB2FL] No Fault, FailCount= 2
2019-07-19T20:37:51.714Z,1563568671.714 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:37:51.714Z,1563568671.714 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:37:56.944Z,1563568676.944 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T20:37:56.944Z,1563568676.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-07-19T20:37:56.944Z,1563568676.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T20:37:56.954Z,1563568676.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T20:37:57.005Z,1563568677.005 [WetLabsBB2FL] Communications Fault, FailCount= 3
2019-07-19T20:37:57.005Z,1563568677.005 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:37:57.362Z,1563568677.362 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T20:37:57.362Z,1563568677.362 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-07-19T20:37:57.362Z,1563568677.362 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:37:57.505Z,1563568677.505 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:37:58.562Z,1563568678.562 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:37:58.562Z,1563568678.562 [WetLabsBB2FL] No Fault, FailCount= 3
2019-07-19T20:37:58.718Z,1563568678.718 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:37:58.718Z,1563568678.718 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:38:02.191Z,1563568682.191 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-19T20:38:02.191Z,1563568682.191 [Default:CheckIn:C.Wait] Stopped
2019-07-19T20:38:02.191Z,1563568682.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T20:38:02.191Z,1563568682.191 [Default:CheckIn:D] Running Loop=1
2019-07-19T20:38:02.599Z,1563568682.599 [Default:CheckIn:D] Stopped
2019-07-19T20:38:02.599Z,1563568682.599 [Default:CheckIn:E] Running Loop=1
2019-07-19T20:38:03.004Z,1563568683.004 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.315995 min
2019-07-19T20:38:03.004Z,1563568683.004 [Default:CheckIn:E] Stopped
2019-07-19T20:38:03.004Z,1563568683.004 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-19T20:38:03.004Z,1563568683.004 [Default:CheckIn] Stopped
2019-07-19T20:38:03.004Z,1563568683.004 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T20:38:03.004Z,1563568683.004 [Default:CheckIn](INFO): Running loop #7
2019-07-19T20:38:03.004Z,1563568683.004 [Default:CheckIn] Running Loop=7
2019-07-19T20:38:03.004Z,1563568683.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T20:38:03.005Z,1563568683.005 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T20:38:04.009Z,1563568684.009 [WetLabsBB2FL] Communications Fault, FailCount= 4
2019-07-19T20:38:04.009Z,1563568684.009 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:38:04.219Z,1563568684.219 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:38:04.509Z,1563568684.509 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:38:04.594Z,1563568684.594 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-19T20:38:04.594Z,1563568684.594 [NAL9602] Data Fault, FailCount= 5
2019-07-19T20:38:04.594Z,1563568684.594 [NAL9602](ERROR): Data Fault
2019-07-19T20:38:04.628Z,1563568684.628 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-19T20:38:04.628Z,1563568684.628 [CBIT](CRITICAL): Data Fault in component: NAL9602
2019-07-19T20:38:05.006Z,1563568685.006 [NAL9602](INFO): Powering down
2019-07-19T20:38:05.035Z,1563568685.035 [CBIT](INFO): Critical error at 20190719T203804
2019-07-19T20:38:05.035Z,1563568685.035 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-07-19T20:38:05.431Z,1563568685.431 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:38:05.431Z,1563568685.431 [WetLabsBB2FL] No Fault, FailCount= 4
2019-07-19T20:38:05.722Z,1563568685.722 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:38:05.722Z,1563568685.722 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:38:11.013Z,1563568691.013 [WetLabsBB2FL] Communications Fault, FailCount= 5
2019-07-19T20:38:11.013Z,1563568691.013 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:38:11.134Z,1563568691.134 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:38:11.135Z,1563568691.135 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:38:11.513Z,1563568691.513 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:38:16.642Z,1563568696.642 [CommandLine](IMPORTANT): got command show stack
2019-07-19T20:38:16.643Z,1563568696.643 [CommandLine](IMPORTANT): Behavior Stack:
2019-07-19T20:38:16.643Z,1563568696.643 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-07-19T20:38:16.643Z,1563568696.643 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2019-07-19T20:38:56.611Z,1563568736.611 [CommandLine](IMPORTANT): got command set sample.NumberOfSamples 3.000000
2019-07-19T20:38:56.611Z,1563568736.611 [CommandLine](FAULT): Incomplete syntax. Try: help set
2019-07-19T20:39:04.758Z,1563568744.758 [CommandLine](IMPORTANT): got command set sample.NumberOfSamples 3.000000 count
2019-07-19T20:39:07.315Z,1563568747.315 [CommandLine](IMPORTANT): got command run
2019-07-19T20:39:07.326Z,1563568747.326 [CommandLine](IMPORTANT): Running
2019-07-19T20:39:07.647Z,1563568747.647 [Default] Stopped
2019-07-19T20:39:07.647Z,1563568747.647 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-19T20:39:07.647Z,1563568747.647 [Default:B.GoToSurface] Stopped
2019-07-19T20:39:07.647Z,1563568747.647 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-19T20:39:07.647Z,1563568747.647 [Default:CheckIn] Stopped
2019-07-19T20:39:07.647Z,1563568747.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T20:39:07.647Z,1563568747.647 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T20:39:07.648Z,1563568747.648 [MissionManager](IMPORTANT): Started mission sample
2019-07-19T20:39:07.648Z,1563568747.648 [sample] Running Loop=1
2019-07-19T20:39:07.648Z,1563568747.648 [sample](DEBUG): Aggregate::initialize sample
2019-07-19T20:39:07.648Z,1563568747.648 [sample:A.AbortSample] Running Loop=1
2019-07-19T20:39:07.648Z,1563568747.648 [sample:A.AbortSample](INFO): Initializing AbortSample.
2019-07-19T20:39:07.648Z,1563568747.648 [sample:B.Pitch] Running Loop=1
2019-07-19T20:39:07.648Z,1563568747.648 [sample:B.Pitch](DEBUG): Initialize.
2019-07-19T20:39:07.648Z,1563568747.648 [sample:C.SetSpeed] Running Loop=1
2019-07-19T20:39:07.649Z,1563568747.649 [sample:C.SetSpeed](DEBUG): Initialize.
2019-07-19T20:39:07.649Z,1563568747.649 [sample:E] Running Loop=1
2019-07-19T20:39:07.649Z,1563568747.649 [sample:E] Stopped
2019-07-19T20:39:07.649Z,1563568747.649 [sample:F] Running Loop=1
2019-07-19T20:39:07.649Z,1563568747.649 [sample:C.SetSpeed] Running Loop=1
2019-07-19T20:39:07.650Z,1563568747.650 [sample:B.Pitch] Running Loop=1
2019-07-19T20:39:07.650Z,1563568747.650 [sample:A.AbortSample] Running Loop=1
2019-07-19T20:39:07.650Z,1563568747.650 [MissionManager](INFO): MissionManager is completed.
2019-07-19T20:39:07.650Z,1563568747.650 [MissionManager](INFO): Uninitializing Mission sample
2019-07-19T20:39:07.650Z,1563568747.650 [sample] Stopped
2019-07-19T20:39:07.650Z,1563568747.650 [sample](DEBUG): Aggregate::uninitialize sample
2019-07-19T20:39:07.650Z,1563568747.650 [sample:A.AbortSample] Stopped
2019-07-19T20:39:07.650Z,1563568747.650 [sample:A.AbortSample](INFO): Uninitializing AbortSample.
2019-07-19T20:39:07.651Z,1563568747.651 [sample:B.Pitch] Stopped
2019-07-19T20:39:07.651Z,1563568747.651 [sample:C.SetSpeed] Stopped
2019-07-19T20:39:07.651Z,1563568747.651 [sample:C.SetSpeed](DEBUG): Uninitialize.
2019-07-19T20:39:07.651Z,1563568747.651 [sample:F] Stopped
2019-07-19T20:39:07.666Z,1563568747.666 [Reporter](INFO): VerticalControl.depthCmd no_value
2019-07-19T20:39:07.667Z,1563568747.667 [Reporter](INFO): ESPComponent.cartridge no_value
2019-07-19T20:39:08.052Z,1563568748.052 [MissionManager](IMPORTANT): Started mission Default
2019-07-19T20:39:08.052Z,1563568748.052 [Default] Running Loop=1
2019-07-19T20:39:08.052Z,1563568748.052 [Default](DEBUG): Aggregate::initialize Default
2019-07-19T20:39:08.052Z,1563568748.052 [Default:B.GoToSurface] Running Loop=1
2019-07-19T20:39:08.052Z,1563568748.052 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-19T20:39:08.052Z,1563568748.052 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-19T20:39:08.053Z,1563568748.053 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-19T20:39:08.053Z,1563568748.053 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-19T20:39:08.053Z,1563568748.053 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-19T20:39:08.054Z,1563568748.054 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-19T20:39:08.054Z,1563568748.054 [Default:A.Wait] Running Loop=1
2019-07-19T20:39:08.054Z,1563568748.054 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-19T20:39:21.381Z,1563568761.381 [Default:A.Wait](INFO): Done Waiting.
2019-07-19T20:39:21.381Z,1563568761.381 [Default:A.Wait] Stopped
2019-07-19T20:39:21.381Z,1563568761.381 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T20:39:21.787Z,1563568761.787 [Default:CheckIn] Running Loop=1
2019-07-19T20:39:21.788Z,1563568761.788 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T20:39:21.788Z,1563568761.788 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T20:39:58.136Z,1563568798.136 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T20:39:58.136Z,1563568798.136 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-07-19T20:39:58.136Z,1563568798.136 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T20:39:58.146Z,1563568798.146 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T20:39:58.556Z,1563568798.556 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T20:39:58.556Z,1563568798.556 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-07-19T20:40:04.610Z,1563568804.610 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-19T20:40:04.611Z,1563568804.611 [NAL9602] No Fault, FailCount= 5
2019-07-19T20:40:04.987Z,1563568804.987 [NAL9602](INFO): Powering up NAL9602
2019-07-19T20:40:12.553Z,1563568812.553 [CommandLine](IMPORTANT): got command stop
2019-07-19T20:40:12.554Z,1563568812.554 [CommandLine](IMPORTANT): Scheduling is paused
2019-07-19T20:40:15.898Z,1563568815.898 [NAL9602](INFO): NAL9602 initialized
2019-07-19T20:40:16.717Z,1563568816.717 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:40:30.830Z,1563568830.830 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml
2019-07-19T20:40:30.831Z,1563568830.831 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
2019-07-19T20:40:30.903Z,1563568830.903 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
2019-07-19T20:40:30.913Z,1563568830.913 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
2019-07-19T20:40:30.915Z,1563568830.915 [MissionManager](INFO): DefineArg sample.CartridgeType = -1.000000 count
2019-07-19T20:40:30.917Z,1563568830.917 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count
2019-07-19T20:40:30.919Z,1563568830.919 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min
2019-07-19T20:40:30.920Z,1563568830.920 [sample:A.AbortSample](DEBUG): Construct.
2019-07-19T20:40:30.922Z,1563568830.922 [sample:B.Pitch](DEBUG): Construct.
2019-07-19T20:40:30.935Z,1563568830.935 [Reporter](INFO): VerticalControl.depthCmd 2.500000 m
2019-07-19T20:40:30.938Z,1563568830.938 [sample:C.SetSpeed](DEBUG): Construct.
2019-07-19T20:40:30.940Z,1563568830.940 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
2019-07-19T20:40:31.078Z,1563568831.078 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
2019-07-19T20:40:31.080Z,1563568831.080 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 10.000000 s
2019-07-19T20:40:31.082Z,1563568831.082 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
2019-07-19T20:40:31.084Z,1563568831.084 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
2019-07-19T20:40:31.087Z,1563568831.087 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool
2019-07-19T20:40:31.090Z,1563568831.090 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool
2019-07-19T20:40:31.092Z,1563568831.092 [MissionManager](INFO): DefineArg sample:SampleAtDepth.ESPCartridgeType = nan count
2019-07-19T20:40:31.094Z,1563568831.094 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2019-07-19T20:40:31.096Z,1563568831.096 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2019-07-19T20:40:31.099Z,1563568831.099 [MissionManager](INFO): DefineArg sample:SampleAtDepth.BuoyancyNeutral = 273.999991 cc
2019-07-19T20:40:31.102Z,1563568831.102 [sample:SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Construct.
2019-07-19T20:40:31.106Z,1563568831.106 [sample:SampleAtDepth:ApproachDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:40:31.109Z,1563568831.109 [sample:SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Construct.
2019-07-19T20:40:31.115Z,1563568831.115 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Construct Wait.
2019-07-19T20:40:31.122Z,1563568831.122 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:40:31.124Z,1563568831.124 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-07-19T20:40:31.125Z,1563568831.125 [MissionManager](ERROR): Slate does not contain celsius
2019-07-19T20:40:31.128Z,1563568831.128 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:40:31.136Z,1563568831.136 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-07-19T20:40:31.136Z,1563568831.136 [MissionManager](ERROR): Slate does not contain celsius
2019-07-19T20:40:31.188Z,1563568831.188 [MissionManager](DEBUG):
This mission is designed to be run in the test tank only and samples
whichever sampler is installed once the vehicle reaches the target
depth.
How long to let the mission run.
90
Depth to sample at.
7
Specifies ESP cartridge type code.
-1
Number of samples to take.
1
How long to wait between samples. This starts *before* the first sample
to exercise the SettleTime setting in the insert aggregate.
3
0
2019-07-19T20:40:31.188Z,1563568831.188 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml
2019-07-19T20:40:31.327Z,1563568831.327 [Reporter](INFO): ESPComponent.cartridge -9 count
2019-07-19T20:40:41.391Z,1563568841.391 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:40:41.391Z,1563568841.391 [WetLabsBB2FL] No Fault, FailCount= 5
2019-07-19T20:40:41.398Z,1563568841.398 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:40:41.398Z,1563568841.398 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:40:46.693Z,1563568846.693 [WetLabsBB2FL] Communications Fault, FailCount= 1
2019-07-19T20:40:46.693Z,1563568846.693 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:40:47.039Z,1563568847.039 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:40:47.197Z,1563568847.197 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:40:48.261Z,1563568848.261 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:40:48.261Z,1563568848.261 [WetLabsBB2FL] No Fault, FailCount= 1
2019-07-19T20:40:48.410Z,1563568848.410 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:40:48.410Z,1563568848.410 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:40:53.709Z,1563568853.709 [WetLabsBB2FL] Communications Fault, FailCount= 2
2019-07-19T20:40:53.709Z,1563568853.709 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:40:53.906Z,1563568853.906 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:40:54.209Z,1563568854.209 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:40:55.126Z,1563568855.126 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:40:55.126Z,1563568855.126 [WetLabsBB2FL] No Fault, FailCount= 2
2019-07-19T20:40:55.422Z,1563568855.422 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:40:55.422Z,1563568855.422 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:41:00.717Z,1563568860.717 [WetLabsBB2FL] Communications Fault, FailCount= 3
2019-07-19T20:41:00.717Z,1563568860.717 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:41:00.796Z,1563568860.796 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:41:01.213Z,1563568861.213 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:41:02.387Z,1563568862.387 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:41:02.387Z,1563568862.387 [WetLabsBB2FL] No Fault, FailCount= 3
2019-07-19T20:41:02.426Z,1563568862.426 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:41:02.426Z,1563568862.426 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:41:07.721Z,1563568867.721 [WetLabsBB2FL] Communications Fault, FailCount= 4
2019-07-19T20:41:07.721Z,1563568867.721 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:41:08.052Z,1563568868.052 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:41:08.221Z,1563568868.221 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:41:09.256Z,1563568869.256 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:41:09.256Z,1563568869.256 [WetLabsBB2FL] No Fault, FailCount= 4
2019-07-19T20:41:09.434Z,1563568869.434 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:41:09.434Z,1563568869.434 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:41:14.729Z,1563568874.729 [WetLabsBB2FL] Communications Fault, FailCount= 5
2019-07-19T20:41:14.729Z,1563568874.729 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:41:14.931Z,1563568874.931 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:41:14.931Z,1563568874.931 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:41:15.229Z,1563568875.229 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:41:58.957Z,1563568918.957 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T20:41:58.957Z,1563568918.957 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-07-19T20:41:58.957Z,1563568918.957 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T20:41:58.968Z,1563568918.968 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T20:41:59.378Z,1563568919.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T20:41:59.378Z,1563568919.378 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-07-19T20:43:06.759Z,1563568986.759 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml
2019-07-19T20:43:06.759Z,1563568986.759 [MissionManager](INFO): Uninitializing Mission sample
2019-07-19T20:43:06.766Z,1563568986.766 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
2019-07-19T20:43:06.803Z,1563568986.803 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
2019-07-19T20:43:06.805Z,1563568986.805 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
2019-07-19T20:43:06.807Z,1563568986.807 [MissionManager](INFO): DefineArg sample.CartridgeType = -1.000000 count
2019-07-19T20:43:06.809Z,1563568986.809 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count
2019-07-19T20:43:06.812Z,1563568986.812 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min
2019-07-19T20:43:06.813Z,1563568986.813 [sample:A.AbortSample](DEBUG): Construct.
2019-07-19T20:43:06.822Z,1563568986.822 [sample:B.Pitch](DEBUG): Construct.
2019-07-19T20:43:06.869Z,1563568986.869 [sample:C.SetSpeed](DEBUG): Construct.
2019-07-19T20:43:06.871Z,1563568986.871 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
2019-07-19T20:43:06.895Z,1563568986.895 [Reporter](INFO): ESPComponent.cartridge no_value
2019-07-19T20:43:07.049Z,1563568987.049 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
2019-07-19T20:43:07.051Z,1563568987.051 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 10.000000 s
2019-07-19T20:43:07.071Z,1563568987.071 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
2019-07-19T20:43:07.073Z,1563568987.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
2019-07-19T20:43:07.076Z,1563568987.076 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool
2019-07-19T20:43:07.078Z,1563568987.078 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool
2019-07-19T20:43:07.080Z,1563568987.080 [MissionManager](INFO): DefineArg sample:SampleAtDepth.ESPCartridgeType = nan count
2019-07-19T20:43:07.082Z,1563568987.082 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2019-07-19T20:43:07.085Z,1563568987.085 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2019-07-19T20:43:07.087Z,1563568987.087 [MissionManager](INFO): DefineArg sample:SampleAtDepth.BuoyancyNeutral = 273.999991 cc
2019-07-19T20:43:07.090Z,1563568987.090 [sample:SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Construct.
2019-07-19T20:43:07.095Z,1563568987.095 [sample:SampleAtDepth:ApproachDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:43:07.098Z,1563568987.098 [sample:SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Construct.
2019-07-19T20:43:07.103Z,1563568987.103 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Construct Wait.
2019-07-19T20:43:07.110Z,1563568987.110 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:43:07.113Z,1563568987.113 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-07-19T20:43:07.113Z,1563568987.113 [MissionManager](ERROR): Slate does not contain celsius
2019-07-19T20:43:07.117Z,1563568987.117 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-19T20:43:07.124Z,1563568987.124 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-07-19T20:43:07.125Z,1563568987.125 [MissionManager](ERROR): Slate does not contain celsius
2019-07-19T20:43:07.142Z,1563568987.142 [MissionManager](DEBUG):
This mission is designed to be run in the test tank only and samples
whichever sampler is installed once the vehicle reaches the target
depth.
How long to let the mission run.
90
Depth to sample at.
7
Specifies ESP cartridge type code.
-1
Number of samples to take.
1
How long to wait between samples. This starts *before* the first sample
to exercise the SettleTime setting in the insert aggregate.
3
0
2019-07-19T20:43:07.142Z,1563568987.142 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml
2019-07-19T20:43:07.286Z,1563568987.286 [Reporter](INFO): ESPComponent.cartridge -9 count
2019-07-19T20:43:20.147Z,1563569000.147 [CommandLine](IMPORTANT): got command run
2019-07-19T20:43:20.149Z,1563569000.149 [CommandLine](IMPORTANT): Running
2019-07-19T20:43:20.188Z,1563569000.188 [Default] Stopped
2019-07-19T20:43:20.188Z,1563569000.188 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-19T20:43:20.188Z,1563569000.188 [Default:B.GoToSurface] Stopped
2019-07-19T20:43:20.188Z,1563569000.188 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-19T20:43:20.188Z,1563569000.188 [Default:CheckIn] Stopped
2019-07-19T20:43:20.188Z,1563569000.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-19T20:43:20.188Z,1563569000.188 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T20:43:20.188Z,1563569000.188 [MissionManager](IMPORTANT): Started mission sample
2019-07-19T20:43:20.189Z,1563569000.189 [sample] Running Loop=1
2019-07-19T20:43:20.189Z,1563569000.189 [sample](DEBUG): Aggregate::initialize sample
2019-07-19T20:43:20.189Z,1563569000.189 [sample:A.AbortSample] Running Loop=1
2019-07-19T20:43:20.189Z,1563569000.189 [sample:A.AbortSample](INFO): Initializing AbortSample.
2019-07-19T20:43:20.189Z,1563569000.189 [sample:B.Pitch] Running Loop=1
2019-07-19T20:43:20.189Z,1563569000.189 [sample:B.Pitch](DEBUG): Initialize.
2019-07-19T20:43:20.190Z,1563569000.190 [sample:C.SetSpeed] Running Loop=1
2019-07-19T20:43:20.190Z,1563569000.190 [sample:C.SetSpeed](DEBUG): Initialize.
2019-07-19T20:43:20.190Z,1563569000.190 [sample:E] Running Loop=1
2019-07-19T20:43:20.190Z,1563569000.190 [sample:E] Stopped
2019-07-19T20:43:20.190Z,1563569000.190 [sample:F] Running Loop=1
2019-07-19T20:43:20.190Z,1563569000.190 [sample:C.SetSpeed] Running Loop=1
2019-07-19T20:43:20.191Z,1563569000.191 [sample:B.Pitch] Running Loop=1
2019-07-19T20:43:20.191Z,1563569000.191 [sample:A.AbortSample] Running Loop=1
2019-07-19T20:43:20.567Z,1563569000.567 [sample:F] Stopped
2019-07-19T20:43:20.567Z,1563569000.567 [sample:G] Running Loop=1
2019-07-19T20:43:20.967Z,1563569000.967 [sample:G] Stopped
2019-07-19T20:43:20.967Z,1563569000.967 [sample:SampleRepeater] Running Loop=1
2019-07-19T20:43:20.968Z,1563569000.968 [sample:SampleRepeater](DEBUG): Aggregate::initialize sample:SampleRepeater
2019-07-19T20:43:20.968Z,1563569000.968 [sample:SampleRepeater:Sample] Running Loop=1
2019-07-19T20:43:20.968Z,1563569000.968 [sample:SampleRepeater:Sample](DEBUG): Aggregate::initialize sample:SampleRepeater:Sample
2019-07-19T20:43:21.375Z,1563569001.375 [sample:SampleAtDepth] Running Loop=1
2019-07-19T20:43:21.375Z,1563569001.375 [sample:SampleAtDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth
2019-07-19T20:43:21.376Z,1563569001.376 [sample:SampleAtDepth:ApproachDepth] Running Loop=1
2019-07-19T20:43:21.376Z,1563569001.376 [sample:SampleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth:ApproachDepth
2019-07-19T20:43:21.376Z,1563569001.376 [sample:SampleAtDepth:ApproachDepth:A] Running Loop=1
2019-07-19T20:43:21.376Z,1563569001.376 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 7.000000 m
2019-07-19T20:43:21.376Z,1563569001.376 [sample:SampleAtDepth:ApproachDepth:A] Stopped
2019-07-19T20:43:21.377Z,1563569001.377 [sample:SampleAtDepth:ApproachDepth:B.Pitch] Running Loop=1
2019-07-19T20:43:21.377Z,1563569001.377 [sample:SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Initialize.
2019-07-19T20:43:21.790Z,1563569001.790 [Reporter](INFO): VerticalControl.depthCmd 7.000000 m
2019-07-19T20:43:44.892Z,1563569024.892 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:43:44.892Z,1563569024.892 [WetLabsBB2FL] No Fault, FailCount= 5
2019-07-19T20:43:45.157Z,1563569025.157 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:43:45.157Z,1563569025.157 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:43:50.405Z,1563569030.405 [WetLabsBB2FL] Communications Fault, FailCount= 1
2019-07-19T20:43:50.405Z,1563569030.405 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:43:50.574Z,1563569030.574 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:43:50.906Z,1563569030.906 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:43:51.746Z,1563569031.746 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:43:51.746Z,1563569031.746 [WetLabsBB2FL] No Fault, FailCount= 1
2019-07-19T20:43:52.122Z,1563569032.122 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:43:52.122Z,1563569032.122 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:43:55.539Z,1563569035.539 [CommandLine](IMPORTANT): got command get VerticalControl.depthCmd
2019-07-19T20:43:55.539Z,1563569035.539 [CommandLine](IMPORTANT): VerticalControl.depthCmd 7.000000 m
2019-07-19T20:43:57.413Z,1563569037.413 [WetLabsBB2FL] Communications Fault, FailCount= 2
2019-07-19T20:43:57.413Z,1563569037.413 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:43:57.782Z,1563569037.782 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:43:57.917Z,1563569037.917 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:43:58.981Z,1563569038.981 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:43:58.981Z,1563569038.981 [WetLabsBB2FL] No Fault, FailCount= 2
2019-07-19T20:43:59.130Z,1563569039.130 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:43:59.131Z,1563569039.131 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:43:59.765Z,1563569039.765 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T20:43:59.765Z,1563569039.765 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-07-19T20:43:59.765Z,1563569039.765 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T20:43:59.837Z,1563569039.837 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T20:44:00.241Z,1563569040.241 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T20:44:00.241Z,1563569040.241 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-07-19T20:44:04.425Z,1563569044.425 [WetLabsBB2FL] Communications Fault, FailCount= 3
2019-07-19T20:44:04.425Z,1563569044.425 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:44:04.689Z,1563569044.689 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:44:04.925Z,1563569044.925 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:44:05.857Z,1563569045.857 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:44:05.857Z,1563569045.857 [WetLabsBB2FL] No Fault, FailCount= 3
2019-07-19T20:44:06.138Z,1563569046.138 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:44:06.138Z,1563569046.138 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:44:11.429Z,1563569051.429 [WetLabsBB2FL] Communications Fault, FailCount= 4
2019-07-19T20:44:11.429Z,1563569051.429 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:44:11.553Z,1563569051.553 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:44:11.941Z,1563569051.941 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:44:13.125Z,1563569053.125 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:44:13.125Z,1563569053.125 [WetLabsBB2FL] No Fault, FailCount= 4
2019-07-19T20:44:13.154Z,1563569053.154 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:44:13.154Z,1563569053.154 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:44:18.163Z,1563569058.163 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 7.000000 meter
2019-07-19T20:44:18.164Z,1563569058.164 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2019-07-19T20:44:18.399Z,1563569058.399 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,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-19T20:44:18.453Z,1563569058.453 [WetLabsBB2FL] Communications Fault, FailCount= 5
2019-07-19T20:44:18.453Z,1563569058.453 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:44:18.740Z,1563569058.740 [sample:SampleAtDepth:ApproachDepth:B.Pitch] Stopped
2019-07-19T20:44:18.740Z,1563569058.740 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
2019-07-19T20:44:18.740Z,1563569058.740 [sample:SampleAtDepth:ApproachDepth] Stopped
2019-07-19T20:44:18.740Z,1563569058.740 [sample:SampleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:ApproachDepth
2019-07-19T20:44:18.740Z,1563569058.740 [sample:SampleAtDepth:SampleWrapper] Running Loop=1
2019-07-19T20:44:18.741Z,1563569058.741 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper
2019-07-19T20:44:18.741Z,1563569058.741 [sample:SampleAtDepth:SampleWrapper:A.Pitch] Running Loop=1
2019-07-19T20:44:18.741Z,1563569058.741 [sample:SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Initialize.
2019-07-19T20:44:18.741Z,1563569058.741 [sample:SampleAtDepth:SampleWrapper:B] Running Loop=1
2019-07-19T20:44:18.806Z,1563569058.806 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:44:18.806Z,1563569058.806 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:44:18.953Z,1563569058.953 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:44:19.140Z,1563569059.140 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 7.000000 m , settling for 3.000000 min
2019-07-19T20:44:19.141Z,1563569059.141 [sample:SampleAtDepth:SampleWrapper:B] Stopped
2019-07-19T20:44:19.141Z,1563569059.141 [sample:SampleAtDepth:SampleWrapper:C.Wait] Running Loop=1
2019-07-19T20:44:19.141Z,1563569059.141 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Initialize Wait Component.
2019-07-19T20:44:19.141Z,1563569059.141 [sample:SampleAtDepth:SampleWrapper:A.Pitch] Running Loop=1
2019-07-19T20:44:36.077Z,1563569076.077 [CommandLine](IMPORTANT): got command stop
2019-07-19T20:44:36.078Z,1563569076.078 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-07-19T20:44:36.123Z,1563569076.123 [MissionManager](INFO): MissionManager is completed.
2019-07-19T20:44:36.123Z,1563569076.123 [MissionManager](INFO): Uninitializing Mission sample
2019-07-19T20:44:36.123Z,1563569076.123 [sample] Stopped
2019-07-19T20:44:36.123Z,1563569076.123 [sample](DEBUG): Aggregate::uninitialize sample
2019-07-19T20:44:36.123Z,1563569076.123 [sample:A.AbortSample] Stopped
2019-07-19T20:44:36.123Z,1563569076.123 [sample:A.AbortSample](INFO): Uninitializing AbortSample.
2019-07-19T20:44:36.123Z,1563569076.123 [sample:B.Pitch] Stopped
2019-07-19T20:44:36.123Z,1563569076.123 [sample:C.SetSpeed] Stopped
2019-07-19T20:44:36.123Z,1563569076.123 [sample:C.SetSpeed](DEBUG): Uninitialize.
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleAtDepth] Stopped
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleAtDepth](DEBUG): Aggregate::uninitialize sample:SampleAtDepth
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleAtDepth:SampleWrapper] Stopped
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleAtDepth:SampleWrapper:A.Pitch] Stopped
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleAtDepth:SampleWrapper:C.Wait] Stopped
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleRepeater] Stopped
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleRepeater](DEBUG): Aggregate::uninitialize sample:SampleRepeater
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleRepeater:Sample] Stopped
2019-07-19T20:44:36.124Z,1563569076.124 [sample:SampleRepeater:Sample](DEBUG): Aggregate::uninitialize sample:SampleRepeater:Sample
2019-07-19T20:44:36.225Z,1563569076.225 [Reporter](INFO): VerticalControl.depthCmd no_value
2019-07-19T20:44:36.225Z,1563569076.225 [Reporter](INFO): ESPComponent.cartridge no_value
2019-07-19T20:44:36.531Z,1563569076.531 [MissionManager](IMPORTANT): Started mission Default
2019-07-19T20:44:36.531Z,1563569076.531 [Default] Running Loop=1
2019-07-19T20:44:36.532Z,1563569076.532 [Default](DEBUG): Aggregate::initialize Default
2019-07-19T20:44:36.532Z,1563569076.532 [Default:B.GoToSurface] Running Loop=1
2019-07-19T20:44:36.532Z,1563569076.532 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-19T20:44:36.532Z,1563569076.532 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-19T20:44:36.532Z,1563569076.532 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-19T20:44:36.533Z,1563569076.533 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-19T20:44:36.533Z,1563569076.533 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-19T20:44:36.534Z,1563569076.534 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-19T20:44:36.534Z,1563569076.534 [Default:A.Wait] Running Loop=1
2019-07-19T20:44:36.534Z,1563569076.534 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-19T20:44:41.555Z,1563569081.555 [CommandLine](IMPORTANT): got command maintain clear
2019-07-19T20:44:41.930Z,1563569081.930 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,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-19T20:44:49.908Z,1563569089.908 [Default:A.Wait](INFO): Done Waiting.
2019-07-19T20:44:49.908Z,1563569089.908 [Default:A.Wait] Stopped
2019-07-19T20:44:49.908Z,1563569089.908 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-19T20:44:50.312Z,1563569090.312 [Default:CheckIn] Running Loop=1
2019-07-19T20:44:50.312Z,1563569090.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-19T20:44:50.312Z,1563569090.312 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-19T20:45:19.374Z,1563569119.374 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-19T20:45:20.193Z,1563569120.193 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:23.021Z,1563569123.021 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:25.845Z,1563569125.845 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:29.081Z,1563569129.081 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:31.909Z,1563569131.909 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:35.145Z,1563569135.145 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:37.969Z,1563569137.969 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:40.797Z,1563569140.797 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:42.821Z,1563569142.821 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:46.049Z,1563569146.049 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:48.877Z,1563569148.877 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:52.109Z,1563569152.109 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:54.941Z,1563569154.941 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:45:58.169Z,1563569158.169 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:00.601Z,1563569160.601 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T20:46:00.601Z,1563569160.601 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-07-19T20:46:00.601Z,1563569160.601 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T20:46:00.639Z,1563569160.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T20:46:00.997Z,1563569160.997 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:01.057Z,1563569161.057 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T20:46:01.058Z,1563569161.058 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-07-19T20:46:03.829Z,1563569163.829 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:05.845Z,1563569165.845 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:09.077Z,1563569169.077 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:11.909Z,1563569171.909 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:15.137Z,1563569175.137 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:17.965Z,1563569177.965 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:21.197Z,1563569181.197 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:24.021Z,1563569184.021 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:26.857Z,1563569186.857 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:28.873Z,1563569188.873 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:30.893Z,1563569190.893 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:34.125Z,1563569194.125 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:36.953Z,1563569196.953 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:40.189Z,1563569200.189 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:43.016Z,1563569203.016 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:46.245Z,1563569206.245 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:48.692Z,1563569208.692 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:46:48.692Z,1563569208.692 [WetLabsBB2FL] No Fault, FailCount= 5
2019-07-19T20:46:48.838Z,1563569208.838 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:46:48.838Z,1563569208.838 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:46:49.073Z,1563569209.073 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:51.901Z,1563569211.901 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:53.921Z,1563569213.921 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:54.129Z,1563569214.129 [WetLabsBB2FL] Communications Fault, FailCount= 1
2019-07-19T20:46:54.129Z,1563569214.129 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:46:54.374Z,1563569214.374 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:46:54.629Z,1563569214.629 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:46:55.553Z,1563569215.553 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:46:55.553Z,1563569215.553 [WetLabsBB2FL] No Fault, FailCount= 1
2019-07-19T20:46:55.842Z,1563569215.842 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:46:55.842Z,1563569215.842 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:46:57.157Z,1563569217.157 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:46:59.989Z,1563569219.989 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:01.133Z,1563569221.133 [WetLabsBB2FL] Communications Fault, FailCount= 2
2019-07-19T20:47:01.133Z,1563569221.133 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:47:01.215Z,1563569221.215 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:47:01.633Z,1563569221.633 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:47:02.832Z,1563569222.832 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:47:02.832Z,1563569222.832 [WetLabsBB2FL] No Fault, FailCount= 2
2019-07-19T20:47:02.846Z,1563569222.846 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:47:02.846Z,1563569222.846 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:47:03.221Z,1563569223.221 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:06.041Z,1563569226.041 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:08.137Z,1563569228.137 [WetLabsBB2FL] Communications Fault, FailCount= 3
2019-07-19T20:47:08.137Z,1563569228.137 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:47:08.484Z,1563569228.484 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:47:08.637Z,1563569228.637 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:47:09.273Z,1563569229.273 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:09.711Z,1563569229.711 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:47:09.711Z,1563569229.711 [WetLabsBB2FL] No Fault, FailCount= 3
2019-07-19T20:47:09.850Z,1563569229.850 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:47:09.850Z,1563569229.850 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:47:12.101Z,1563569232.101 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:14.929Z,1563569234.929 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:15.141Z,1563569235.141 [WetLabsBB2FL] Communications Fault, FailCount= 4
2019-07-19T20:47:15.141Z,1563569235.141 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:47:15.409Z,1563569235.409 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:47:15.641Z,1563569235.641 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:47:16.591Z,1563569236.591 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:47:16.591Z,1563569236.591 [WetLabsBB2FL] No Fault, FailCount= 4
2019-07-19T20:47:16.861Z,1563569236.861 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:47:16.861Z,1563569236.861 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:47:16.959Z,1563569236.959 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:18.969Z,1563569238.969 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:22.145Z,1563569242.145 [WetLabsBB2FL] Communications Fault, FailCount= 5
2019-07-19T20:47:22.145Z,1563569242.145 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:47:22.197Z,1563569242.197 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:22.228Z,1563569242.228 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:47:22.228Z,1563569242.228 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:47:22.641Z,1563569242.641 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:47:25.029Z,1563569245.029 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:28.261Z,1563569248.261 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:31.089Z,1563569251.089 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:34.317Z,1563569254.317 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:37.153Z,1563569257.153 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:39.981Z,1563569259.981 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:41.997Z,1563569261.997 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:45.229Z,1563569265.229 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:48.057Z,1563569268.057 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:51.285Z,1563569271.285 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:54.121Z,1563569274.121 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:47:57.353Z,1563569277.353 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:00.181Z,1563569280.181 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:01.805Z,1563569281.805 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T20:48:01.805Z,1563569281.805 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2019-07-19T20:48:01.805Z,1563569281.805 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T20:48:01.815Z,1563569281.815 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T20:48:02.221Z,1563569282.221 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T20:48:02.221Z,1563569282.221 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2019-07-19T20:48:03.009Z,1563569283.009 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:05.025Z,1563569285.025 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:08.257Z,1563569288.257 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:11.085Z,1563569291.085 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:14.313Z,1563569294.313 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:17.145Z,1563569297.145 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:20.373Z,1563569300.373 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:23.205Z,1563569303.205 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:26.033Z,1563569306.033 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:28.057Z,1563569308.057 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:30.073Z,1563569310.073 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:33.305Z,1563569313.305 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:36.133Z,1563569316.133 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:39.365Z,1563569319.365 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:42.189Z,1563569322.189 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:45.425Z,1563569325.425 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:48.253Z,1563569328.253 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:51.085Z,1563569331.085 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:53.105Z,1563569333.105 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:55.121Z,1563569335.121 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:48:58.357Z,1563569338.357 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:01.181Z,1563569341.181 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:04.417Z,1563569344.417 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:07.241Z,1563569347.241 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:10.473Z,1563569350.473 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:13.305Z,1563569353.305 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:16.129Z,1563569356.129 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:18.152Z,1563569358.152 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:21.385Z,1563569361.385 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:24.209Z,1563569364.209 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:27.441Z,1563569367.441 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:30.269Z,1563569370.269 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:33.505Z,1563569373.505 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:36.329Z,1563569376.329 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:39.153Z,1563569379.153 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:41.181Z,1563569381.181 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:44.413Z,1563569384.413 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:47.237Z,1563569387.237 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:50.469Z,1563569390.469 [NAL9602](DEBUG): Fix Requested
2019-07-19T20:49:50.496Z,1563569390.496 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-19T20:44:50.3Z
2019-07-19T20:49:50.496Z,1563569390.496 [Default:CheckIn:Read_GPS] Stopped
2019-07-19T20:49:50.496Z,1563569390.496 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-19T20:49:52.163Z,1563569392.163 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:49:52.163Z,1563569392.163 [WetLabsBB2FL] No Fault, FailCount= 5
2019-07-19T20:49:52.534Z,1563569392.534 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:49:52.534Z,1563569392.534 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:49:55.640Z,1563569395.640 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190719T191656/Courier0021.lzma
2019-07-19T20:49:56.446Z,1563569396.446 [DataOverHttps](INFO): Moved sent file to Logs/20190719T191656/Courier0021.lzma.bak
2019-07-19T20:49:56.447Z,1563569396.447 [DataOverHttps](INFO): SBD MOMSN=11456688
2019-07-19T20:49:57.833Z,1563569397.833 [WetLabsBB2FL] Communications Fault, FailCount= 1
2019-07-19T20:49:57.833Z,1563569397.833 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:49:57.838Z,1563569397.838 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:49:58.337Z,1563569398.337 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:49:59.446Z,1563569399.446 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:49:59.446Z,1563569399.446 [WetLabsBB2FL] No Fault, FailCount= 1
2019-07-19T20:49:59.557Z,1563569399.557 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:49:59.557Z,1563569399.557 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:50:02.637Z,1563569402.637 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-19T20:50:02.637Z,1563569402.637 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2019-07-19T20:50:02.637Z,1563569402.637 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-19T20:50:02.648Z,1563569402.648 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-19T20:50:03.057Z,1563569403.057 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-19T20:50:03.058Z,1563569403.058 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2019-07-19T20:50:04.845Z,1563569404.845 [WetLabsBB2FL] Communications Fault, FailCount= 2
2019-07-19T20:50:04.845Z,1563569404.845 [WetLabsBB2FL](ERROR): Communications Fault
2019-07-19T20:50:05.110Z,1563569405.110 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-07-19T20:50:05.345Z,1563569405.345 [WetLabsBB2FL](INFO): Powering down
2019-07-19T20:50:06.318Z,1563569406.318 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-19T20:50:06.318Z,1563569406.318 [WetLabsBB2FL] No Fault, FailCount= 2
2019-07-19T20:50:06.565Z,1563569406.565 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-19T20:50:06.565Z,1563569406.565 [WetLabsBB2FL](INFO): Powering up
2019-07-19T20:50:07.016Z,1563569407.016 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20190719T203722/Courier0000.lzma
2019-07-19T20:50:07.822Z,1563569407.822 [DataOverHttps](INFO): Moved sent file to Logs/20190719T203722/Courier0000.lzma.bak
2019-07-19T20:50:07.823Z,1563569407.823 [DataOverHttps](INFO): SBD MOMSN=11456690