2018-10-23T23:49:21.340Z,1540338561.340 [Supervisor](DEBUG): Initializing supervisor.
2018-10-23T23:49:21.353Z,1540338561.353 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-23T23:49:21.353Z,1540338561.353 [SyncHandler](INFO): Protected caller Thread ID is 800
2018-10-23T23:49:21.354Z,1540338561.354 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-23T23:49:21.365Z,1540338561.365 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-23T23:49:21.365Z,1540338561.365 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801
2018-10-23T23:49:21.368Z,1540338561.368 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-23T23:49:21.390Z,1540338561.390 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-23T23:49:21.401Z,1540338561.401 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-23T23:49:21.401Z,1540338561.401 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802
2018-10-23T23:49:21.402Z,1540338561.402 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-23T23:49:21.413Z,1540338561.413 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-23T23:49:21.413Z,1540338561.413 [logger ThreadHandler](INFO): Protected caller Thread ID is 803
2018-10-23T23:49:21.415Z,1540338561.415 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-23T23:49:21.415Z,1540338561.415 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-23T23:49:21.420Z,1540338561.420 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-23T23:49:22.316Z,1540338562.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-23T23:49:22.318Z,1540338562.318 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-23T23:49:22.780Z,1540338562.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-23T23:49:22.782Z,1540338562.782 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-23T23:49:22.978Z,1540338562.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-23T23:49:22.979Z,1540338562.979 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-23T23:49:23.128Z,1540338563.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-23T23:49:23.130Z,1540338563.130 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-23T23:49:23.572Z,1540338563.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-23T23:49:23.573Z,1540338563.573 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-23T23:49:23.670Z,1540338563.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-23T23:49:23.670Z,1540338563.670 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-23T23:49:23.813Z,1540338563.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-23T23:49:23.814Z,1540338563.814 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-23T23:49:24.155Z,1540338564.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-23T23:49:24.157Z,1540338564.157 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-23T23:49:24.284Z,1540338564.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-23T23:49:24.285Z,1540338564.285 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-23T23:49:24.633Z,1540338564.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-23T23:49:24.634Z,1540338564.634 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-23T23:49:24.840Z,1540338564.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-23T23:49:24.840Z,1540338564.840 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-23T23:49:25.055Z,1540338565.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-23T23:49:25.056Z,1540338565.056 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-23T23:49:25.138Z,1540338565.138 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-23T23:49:25.492Z,1540338565.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-23T23:49:25.492Z,1540338565.492 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-23T23:49:25.812Z,1540338565.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-23T23:49:25.814Z,1540338565.814 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-10-23T23:49:25.818Z,1540338565.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-10-23T23:49:25.904Z,1540338565.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-10-23T23:49:26.055Z,1540338566.055 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-10-23T23:49:26.155Z,1540338566.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-10-23T23:49:26.329Z,1540338566.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-10-23T23:49:26.412Z,1540338566.412 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-10-23T23:49:26.640Z,1540338566.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-23T23:49:26.641Z,1540338566.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-10-23T23:49:26.758Z,1540338566.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-10-23T23:49:26.859Z,1540338566.859 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-10-23T23:49:26.955Z,1540338566.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-10-23T23:49:27.059Z,1540338567.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-10-23T23:49:27.171Z,1540338567.171 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-10-23T23:49:27.175Z,1540338567.175 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-23T23:49:27.235Z,1540338567.235 [DepthRateCalculator] Loaded
2018-10-23T23:49:27.235Z,1540338567.235 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-23T23:49:27.241Z,1540338567.241 [PitchRateCalculator] Loaded
2018-10-23T23:49:27.241Z,1540338567.241 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-23T23:49:27.257Z,1540338567.257 [SpeedCalculator] Loaded
2018-10-23T23:49:27.258Z,1540338567.258 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-23T23:49:27.279Z,1540338567.279 [TempGradientCalculator] Loaded
2018-10-23T23:49:27.279Z,1540338567.279 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-23T23:49:27.285Z,1540338567.285 [YawRateCalculator] Loaded
2018-10-23T23:49:27.285Z,1540338567.285 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-23T23:49:27.326Z,1540338567.326 [ElevatorOffsetCalculator] Loaded
2018-10-23T23:49:27.327Z,1540338567.327 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-23T23:49:27.327Z,1540338567.327 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-23T23:49:27.328Z,1540338567.328 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-23T23:49:27.624Z,1540338567.624 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-23T23:49:27.626Z,1540338567.626 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-23T23:49:28.273Z,1540338568.273 [AHRS_M2] Loaded
2018-10-23T23:49:28.274Z,1540338568.274 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-10-23T23:49:28.411Z,1540338568.411 [DataOverHttps] Loaded
2018-10-23T23:49:28.412Z,1540338568.412 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-23T23:49:28.425Z,1540338568.425 [Depth_Keller] Loaded
2018-10-23T23:49:28.425Z,1540338568.425 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-23T23:49:28.430Z,1540338568.430 [DropWeight] Loaded
2018-10-23T23:49:28.431Z,1540338568.431 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-23T23:49:28.532Z,1540338568.532 [NAL9602] Loaded
2018-10-23T23:49:28.532Z,1540338568.532 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-23T23:49:28.548Z,1540338568.548 [Onboard] Loaded
2018-10-23T23:49:28.548Z,1540338568.548 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-23T23:49:28.559Z,1540338568.559 [Radio_Surface] Loaded
2018-10-23T23:49:28.559Z,1540338568.559 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-23T23:49:28.560Z,1540338568.560 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-10-23T23:49:28.560Z,1540338568.560 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-10-23T23:49:28.567Z,1540338568.567 [SCPI] Loaded
2018-10-23T23:49:28.567Z,1540338568.567 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-10-23T23:49:30.798Z,1540338570.798 [BPC1] Loaded
2018-10-23T23:49:30.799Z,1540338570.799 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-10-23T23:49:30.799Z,1540338570.799 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-23T23:49:30.800Z,1540338570.800 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-23T23:49:30.906Z,1540338570.906 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-23T23:49:30.907Z,1540338570.907 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-23T23:49:31.022Z,1540338571.022 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-10-23T23:49:31.022Z,1540338571.022 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-10-23T23:49:31.078Z,1540338571.078 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-23T23:49:31.079Z,1540338571.079 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-23T23:49:31.095Z,1540338571.095 [NavChart] Loaded
2018-10-23T23:49:31.096Z,1540338571.096 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-23T23:49:31.100Z,1540338571.100 [UniversalFixResidualReporter] Loaded
2018-10-23T23:49:31.100Z,1540338571.100 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-23T23:49:31.100Z,1540338571.100 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-23T23:49:31.101Z,1540338571.101 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-23T23:49:31.383Z,1540338571.383 [CTD_NeilBrown] Loaded
2018-10-23T23:49:31.384Z,1540338571.384 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-10-23T23:49:31.385Z,1540338571.385 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-10-23T23:49:31.385Z,1540338571.385 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 883
2018-10-23T23:49:31.442Z,1540338571.442 [CTD_Seabird] Loaded
2018-10-23T23:49:31.442Z,1540338571.442 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-10-23T23:49:31.443Z,1540338571.443 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-10-23T23:49:31.443Z,1540338571.443 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 884
2018-10-23T23:49:31.494Z,1540338571.494 [WetLabsBB2FL] Loaded
2018-10-23T23:49:31.494Z,1540338571.494 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-23T23:49:31.495Z,1540338571.495 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0
2018-10-23T23:49:31.496Z,1540338571.496 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 885
2018-10-23T23:49:31.496Z,1540338571.496 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-23T23:49:31.497Z,1540338571.497 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-23T23:49:31.589Z,1540338571.589 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-23T23:49:31.691Z,1540338571.691 [VerticalControl] Loaded
2018-10-23T23:49:31.691Z,1540338571.691 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-23T23:49:31.692Z,1540338571.692 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-23T23:49:31.761Z,1540338571.761 [HorizontalControl] Loaded
2018-10-23T23:49:31.762Z,1540338571.762 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-23T23:49:31.762Z,1540338571.762 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-23T23:49:31.768Z,1540338571.768 [SpeedControl] Loaded
2018-10-23T23:49:31.768Z,1540338571.768 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-23T23:49:31.769Z,1540338571.769 [LoopControl](DEBUG): Construct LoopControl.
2018-10-23T23:49:31.769Z,1540338571.769 [LoopControl] Loaded
2018-10-23T23:49:31.769Z,1540338571.769 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-23T23:49:31.770Z,1540338571.770 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-23T23:49:31.771Z,1540338571.771 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-23T23:49:31.794Z,1540338571.794 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-23T23:49:31.795Z,1540338571.795 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-23T23:49:31.957Z,1540338571.957 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-23T23:49:31.969Z,1540338571.969 [SBIT] Loaded
2018-10-23T23:49:31.969Z,1540338571.969 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-23T23:49:31.970Z,1540338571.970 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-23T23:49:31.981Z,1540338571.981 [IBIT] Loaded
2018-10-23T23:49:31.982Z,1540338571.982 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-23T23:49:31.985Z,1540338571.985 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-23T23:49:32.140Z,1540338572.140 [CBIT] Loaded
2018-10-23T23:49:32.140Z,1540338572.140 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-23T23:49:32.141Z,1540338572.141 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-23T23:49:32.142Z,1540338572.142 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-23T23:49:32.276Z,1540338572.276 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-23T23:49:32.277Z,1540338572.277 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-23T23:49:32.315Z,1540338572.315 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-23T23:49:32.316Z,1540338572.316 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-23T23:49:32.440Z,1540338572.440 [BuoyancyServo] Loaded
2018-10-23T23:49:32.440Z,1540338572.440 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-23T23:49:32.452Z,1540338572.452 [ElevatorServo] Loaded
2018-10-23T23:49:32.452Z,1540338572.452 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-23T23:49:32.463Z,1540338572.463 [MassServo] Loaded
2018-10-23T23:49:32.463Z,1540338572.463 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-23T23:49:32.475Z,1540338572.475 [RudderServo] Loaded
2018-10-23T23:49:32.475Z,1540338572.475 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-23T23:49:32.486Z,1540338572.486 [ThrusterServo] Loaded
2018-10-23T23:49:32.486Z,1540338572.486 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-23T23:49:32.486Z,1540338572.486 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-23T23:49:32.490Z,1540338572.490 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-23T23:49:32.491Z,1540338572.491 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-23T23:49:32.497Z,1540338572.497 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-23T23:49:32.498Z,1540338572.498 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2018-10-23T23:49:32.499Z,1540338572.499 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 886
2018-10-23T23:49:32.504Z,1540338572.504 [Supervisor](INFO): Main Thread ID is 796
2018-10-23T23:49:32.504Z,1540338572.504 [Supervisor](DEBUG): Running supervisor.
2018-10-23T23:49:32.504Z,1540338572.504 [CommandLine ThreadHandler](INFO): Handler Thread ID is 887
2018-10-23T23:49:32.507Z,1540338572.507 [controlThread ThreadHandler](INFO): Handler Thread ID is 888
2018-10-23T23:49:32.507Z,1540338572.507 [controlThread](DEBUG): Initializing ControlThread
2018-10-23T23:49:32.508Z,1540338572.508 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-23T23:49:32.508Z,1540338572.508 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-23T23:49:32.509Z,1540338572.509 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-23T23:49:32.509Z,1540338572.509 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-23T23:49:32.510Z,1540338572.510 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-23T23:49:32.510Z,1540338572.510 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-23T23:49:32.515Z,1540338572.515 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-23T23:49:32.516Z,1540338572.516 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:49:32.517Z,1540338572.517 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:49:32.517Z,1540338572.517 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-23T23:49:32.518Z,1540338572.518 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:49:32.518Z,1540338572.518 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:49:32.518Z,1540338572.518 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-23T23:49:32.519Z,1540338572.519 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-23T23:49:32.519Z,1540338572.519 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-23T23:49:32.521Z,1540338572.521 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-23T23:49:32.522Z,1540338572.522 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-23T23:49:32.522Z,1540338572.522 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-23T23:49:32.522Z,1540338572.522 [SBIT](INFO): Initialize SBIT Component.
2018-10-23T23:49:32.523Z,1540338572.523 [SBIT](IMPORTANT): git: 2018-10-09-31-g8f1ca26
2018-10-23T23:49:32.523Z,1540338572.523 [SBIT](INFO): git hash: 8f1ca2616133f591d84f59109522710795260801
2018-10-23T23:49:32.523Z,1540338572.523 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-23T23:49:32.524Z,1540338572.524 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-23T23:49:32.524Z,1540338572.524 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-10-23T23:49:32.525Z,1540338572.525 [IBIT](INFO): Initialize IBIT Component.
2018-10-23T23:49:32.526Z,1540338572.526 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-23T23:49:32.526Z,1540338572.526 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-10-23T23:49:32.526Z,1540338572.526 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-10-23T23:49:32.527Z,1540338572.527 [logger ThreadHandler](INFO): Handler Thread ID is 889
2018-10-23T23:49:32.545Z,1540338572.545 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 890
2018-10-23T23:49:32.551Z,1540338572.551 [Radio_Surface](INFO): Powering up
2018-10-23T23:49:32.557Z,1540338572.557 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 891
2018-10-23T23:49:32.569Z,1540338572.569 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 892
2018-10-23T23:49:32.570Z,1540338572.570 [CTD_Seabird](INFO): Initializing
2018-10-23T23:49:32.570Z,1540338572.570 [CTD_Seabird](INFO): Checking LCM
2018-10-23T23:49:32.635Z,1540338572.635 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-23T23:49:32.683Z,1540338572.683 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 893
2018-10-23T23:49:32.685Z,1540338572.685 [WetLabsBB2FL](INFO): Powering down
2018-10-23T23:49:32.694Z,1540338572.694 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 894
2018-10-23T23:49:32.823Z,1540338572.823 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-23T23:49:32.832Z,1540338572.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-23T23:49:32.832Z,1540338572.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-23T23:49:32.837Z,1540338572.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-23T23:49:32.845Z,1540338572.845 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2018-10-23T23:49:32.846Z,1540338572.846 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-23T23:49:32.849Z,1540338572.849 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-23T23:49:32.849Z,1540338572.849 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-23T23:49:32.849Z,1540338572.849 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-23T23:49:32.849Z,1540338572.849 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-23T23:49:32.850Z,1540338572.850 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-23T23:49:32.850Z,1540338572.850 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-23T23:49:32.850Z,1540338572.850 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-23T23:49:32.850Z,1540338572.850 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-23T23:49:32.850Z,1540338572.850 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-23T23:49:32.851Z,1540338572.851 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-23T23:49:32.851Z,1540338572.851 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-23T23:49:32.851Z,1540338572.851 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-23T23:49:32.851Z,1540338572.851 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-23T23:49:32.967Z,1540338572.967 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-23T23:49:32.968Z,1540338572.968 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-23T23:49:32.986Z,1540338572.986 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-23T23:49:33.018Z,1540338573.018 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-23T23:49:33.020Z,1540338573.020 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-23T23:49:33.055Z,1540338573.055 [CTD_Seabird](INFO): LCM OK
2018-10-23T23:49:33.055Z,1540338573.055 [CTD_Seabird](INFO): Powering up
2018-10-23T23:49:33.078Z,1540338573.078 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-23T23:49:33.082Z,1540338573.082 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         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/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2018-10-23T23:49:33.087Z,1540338573.087 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-23T23:49:33.115Z,1540338573.115 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:49:33.238Z,1540338573.238 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-23T23:49:33.278Z,1540338573.278 [Depth_Keller](ERROR): Pressure reading out of range: 798.729736 decibar
2018-10-23T23:49:33.325Z,1540338573.325 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-23T23:49:33.325Z,1540338573.325 [Onboard](FAULT): Can't read temperature LSB.
2018-10-23T23:49:33.326Z,1540338573.326 [Onboard] Hardware Fault, FailCount= 1
2018-10-23T23:49:33.326Z,1540338573.326 [Onboard](ERROR): Hardware Fault
2018-10-23T23:49:33.326Z,1540338573.326 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-23T23:49:33.326Z,1540338573.326 [Onboard](FAULT): Can't read temperature MSB.
2018-10-23T23:49:33.327Z,1540338573.327 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-23T23:49:33.327Z,1540338573.327 [Onboard](FAULT): Can't read pressure LSB.
2018-10-23T23:49:33.328Z,1540338573.328 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-23T23:49:33.328Z,1540338573.328 [Onboard](FAULT): Can't read pressure MSB.
2018-10-23T23:49:33.405Z,1540338573.405 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-23T23:49:33.646Z,1540338573.646 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:49:33.653Z,1540338573.653 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-23T23:49:33.675Z,1540338573.675 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:49:33.681Z,1540338573.681 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-23T23:49:33.703Z,1540338573.703 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:49:33.709Z,1540338573.709 [MassServo](DEBUG): Initializing MassServo.
2018-10-23T23:49:33.715Z,1540338573.715 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:49:33.721Z,1540338573.721 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-23T23:49:33.743Z,1540338573.743 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:49:33.749Z,1540338573.749 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-23T23:49:33.770Z,1540338573.770 [CommandLine](FAULT): Scheduling is paused
2018-10-23T23:49:33.770Z,1540338573.770 [CBIT](INFO): Critical error at 20181023T234932
2018-10-23T23:49:33.770Z,1540338573.770 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-23T23:49:33.774Z,1540338573.774 [CBIT](ERROR): Hardware Fault in component: Onboard
2018-10-23T23:49:33.774Z,1540338573.774 [CBIT](CRITICAL): Hardware Fault in component: Onboard
2018-10-23T23:49:33.894Z,1540338573.894 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-10-23T23:49:33.894Z,1540338573.894 [DropWeight] Hardware Fault, FailCount= 1
2018-10-23T23:49:33.894Z,1540338573.894 [DropWeight](ERROR): Hardware Fault
2018-10-23T23:49:33.913Z,1540338573.913 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-23T23:49:33.938Z,1540338573.938 [CBIT](INFO): Critical error at 20181023T234933
2018-10-23T23:49:33.940Z,1540338573.940 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-10-23T23:49:33.941Z,1540338573.941 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-10-23T23:49:34.553Z,1540338574.553 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-23T23:49:34.553Z,1540338574.553 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-23T23:49:34.553Z,1540338574.553 [BuoyancyServo] Communications Fault, FailCount= 1
2018-10-23T23:49:34.553Z,1540338574.553 [BuoyancyServo](ERROR): Communications Fault
2018-10-23T23:49:34.755Z,1540338574.755 [CBIT](INFO): Critical error at 20181023T234933
2018-10-23T23:49:34.757Z,1540338574.757 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-23T23:49:34.807Z,1540338574.807 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-23T23:49:34.807Z,1540338574.807 [BuoyancyServo](INFO): Powering down
2018-10-23T23:49:36.689Z,1540338576.689 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:37.181Z,1540338577.181 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:37.220Z,1540338577.220 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-23T23:49:37.221Z,1540338577.221 [BuoyancyServo] No Fault, FailCount= 1
2018-10-23T23:49:37.581Z,1540338577.581 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:37.615Z,1540338577.615 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:49:37.734Z,1540338577.734 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-23T23:49:38.132Z,1540338578.132 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-23T23:49:38.173Z,1540338578.173 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:38.206Z,1540338578.206 [Radio_Surface](INFO): Powering down
2018-10-23T23:49:38.300Z,1540338578.300 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-10-23T23:49:38.521Z,1540338578.521 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-10-23T23:49:38.573Z,1540338578.573 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-23T23:49:38.574Z,1540338578.574 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-23T23:49:38.574Z,1540338578.574 [BuoyancyServo] Communications Fault, FailCount= 2
2018-10-23T23:49:38.574Z,1540338578.574 [BuoyancyServo](ERROR): Communications Fault
2018-10-23T23:49:38.742Z,1540338578.742 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-23T23:49:39.109Z,1540338579.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:39.149Z,1540338579.149 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-23T23:49:39.149Z,1540338579.149 [CTD_Seabird](INFO): Powering down
2018-10-23T23:49:39.178Z,1540338579.178 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-23T23:49:39.178Z,1540338579.178 [BuoyancyServo](INFO): Powering down
2018-10-23T23:49:39.557Z,1540338579.557 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:39.969Z,1540338579.969 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:40.377Z,1540338580.377 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:40.777Z,1540338580.777 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:41.169Z,1540338581.169 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:41.234Z,1540338581.234 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-23T23:49:41.234Z,1540338581.234 [BuoyancyServo] No Fault, FailCount= 2
2018-10-23T23:49:41.577Z,1540338581.577 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:41.611Z,1540338581.611 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:49:41.730Z,1540338581.730 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-23T23:49:42.093Z,1540338582.093 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:42.493Z,1540338582.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:42.622Z,1540338582.622 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2139112%. Temp:4878168 C. ABORTING MISSION
2018-10-23T23:49:42.953Z,1540338582.953 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:43.017Z,1540338583.017 [CBIT](INFO): Critical error at 20181023T234942
2018-10-23T23:49:43.357Z,1540338583.357 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:43.357Z,1540338583.357 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:49:43.357Z,1540338583.357 [AHRS_M2] Communications Fault, FailCount= 1
2018-10-23T23:49:43.357Z,1540338583.357 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:49:43.426Z,1540338583.426 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:49:43.433Z,1540338583.433 [AHRS_M2](INFO): Powering down
2018-10-23T23:49:47.081Z,1540338587.081 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:49:47.081Z,1540338587.081 [AHRS_M2] No Fault, FailCount= 1
2018-10-23T23:49:47.433Z,1540338587.433 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:49:50.957Z,1540338590.957 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:51.357Z,1540338591.357 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:51.757Z,1540338591.757 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:52.157Z,1540338592.157 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:52.557Z,1540338592.557 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:52.965Z,1540338592.965 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:53.373Z,1540338593.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:53.761Z,1540338593.761 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:54.169Z,1540338594.169 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:54.569Z,1540338594.569 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:54.969Z,1540338594.969 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:55.369Z,1540338595.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:55.769Z,1540338595.769 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:56.169Z,1540338596.169 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:56.569Z,1540338596.569 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:56.973Z,1540338596.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:57.377Z,1540338597.377 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:57.777Z,1540338597.777 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:49:57.777Z,1540338597.777 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:49:57.777Z,1540338597.777 [AHRS_M2] Communications Fault, FailCount= 2
2018-10-23T23:49:57.777Z,1540338597.777 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:49:57.857Z,1540338597.857 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:49:57.873Z,1540338597.873 [AHRS_M2](INFO): Powering down
2018-10-23T23:49:58.674Z,1540338598.674 [NAL9602](INFO): Powering up NAL9602
2018-10-23T23:50:01.145Z,1540338601.145 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-23T23:50:01.150Z,1540338601.150 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-23T23:50:01.614Z,1540338601.614 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:50:01.614Z,1540338601.614 [AHRS_M2] No Fault, FailCount= 2
2018-10-23T23:50:01.869Z,1540338601.869 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:50:05.393Z,1540338605.393 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:05.913Z,1540338605.913 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:06.429Z,1540338606.429 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:06.905Z,1540338606.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:07.425Z,1540338607.425 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:07.873Z,1540338607.873 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:08.281Z,1540338608.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:08.721Z,1540338608.721 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:09.261Z,1540338609.261 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:09.681Z,1540338609.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:09.692Z,1540338609.692 [NAL9602](INFO): NAL9602 initialized
2018-10-23T23:50:10.125Z,1540338610.125 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:10.665Z,1540338610.665 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:11.093Z,1540338611.093 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:11.517Z,1540338611.517 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:12.057Z,1540338612.057 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:12.057Z,1540338612.057 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:50:12.057Z,1540338612.057 [AHRS_M2] Communications Fault, FailCount= 3
2018-10-23T23:50:12.057Z,1540338612.057 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:50:12.150Z,1540338612.150 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:50:12.157Z,1540338612.157 [AHRS_M2](INFO): Powering down
2018-10-23T23:50:13.469Z,1540338613.469 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007798
CHAN A1 (24V): 0.002060
CHAN A2 (12V): -0.005299
CHAN A3 (5V): -0.002630
CHAN B0 (3.3V): -0.001490
CHAN B1 (3.15aV): -0.001468
CHAN B2 (3.15bV): -0.001237
CHAN B3 (GND): -0.000409
OPEN: -0.001166
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-23T23:50:14.248Z,1540338614.248 [SBIT](FAULT): Mass: EXPECTED:0.009000 ACTUAL:0.005883
2018-10-23T23:50:15.826Z,1540338615.826 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:50:15.826Z,1540338615.826 [AHRS_M2] No Fault, FailCount= 3
2018-10-23T23:50:16.161Z,1540338616.161 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:50:19.677Z,1540338619.677 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:20.165Z,1540338620.165 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:20.625Z,1540338620.625 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:21.101Z,1540338621.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:21.581Z,1540338621.581 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:22.109Z,1540338622.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:22.737Z,1540338622.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:23.181Z,1540338623.181 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:23.597Z,1540338623.597 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:24.029Z,1540338624.029 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:24.449Z,1540338624.449 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:24.857Z,1540338624.857 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:25.297Z,1540338625.297 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:25.717Z,1540338625.717 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:26.149Z,1540338626.149 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:26.549Z,1540338626.549 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:26.549Z,1540338626.549 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:50:26.549Z,1540338626.549 [AHRS_M2] Communications Fault, FailCount= 4
2018-10-23T23:50:26.549Z,1540338626.549 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:50:26.710Z,1540338626.710 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:50:26.719Z,1540338626.719 [AHRS_M2](INFO): Powering down
2018-10-23T23:50:30.345Z,1540338630.345 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:50:30.345Z,1540338630.345 [AHRS_M2] No Fault, FailCount= 4
2018-10-23T23:50:30.721Z,1540338630.721 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:50:34.245Z,1540338634.245 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:34.653Z,1540338634.653 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:35.062Z,1540338635.062 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:35.469Z,1540338635.469 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:35.869Z,1540338635.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:36.269Z,1540338636.269 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:36.669Z,1540338636.669 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:37.069Z,1540338637.069 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:37.462Z,1540338637.462 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:37.869Z,1540338637.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:38.269Z,1540338638.269 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:38.677Z,1540338638.677 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:39.077Z,1540338639.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:39.481Z,1540338639.481 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:39.889Z,1540338639.889 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:40.285Z,1540338640.285 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:40.685Z,1540338640.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:40.721Z,1540338640.721 [BPC1](ERROR): BPC1B: No match for serial number 0273 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2018-10-23T23:50:41.085Z,1540338641.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:50:41.085Z,1540338641.085 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:50:41.085Z,1540338641.085 [AHRS_M2] Communications Fault, FailCount= 5
2018-10-23T23:50:41.085Z,1540338641.085 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:50:41.096Z,1540338641.096 [BPC1](ERROR): BPC1A: No match for serial number 0226 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2018-10-23T23:50:41.128Z,1540338641.128 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-10-23T23:50:41.128Z,1540338641.128 [BPC1] Data Fault, FailCount= 1
2018-10-23T23:50:41.128Z,1540338641.128 [BPC1](ERROR): Data Fault
2018-10-23T23:50:41.174Z,1540338641.174 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:50:41.174Z,1540338641.174 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2018-10-23T23:50:41.175Z,1540338641.175 [CBIT](ERROR): Data Fault in component: BPC1
2018-10-23T23:50:41.190Z,1540338641.190 [AHRS_M2](INFO): Powering down
2018-10-23T23:50:41.286Z,1540338641.286 [CBIT](INFO): Critical error at 20181023T235041
2018-10-23T23:50:54.902Z,1540338654.902 [SBIT](CRITICAL): SBIT FAILED
2018-10-23T23:50:54.954Z,1540338654.954 [CommandLine](IMPORTANT): got command configSet list
2018-10-23T23:50:54.954Z,1540338654.954 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-23T23:50:54.956Z,1540338654.956 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-10-23T23:50:54.956Z,1540338654.956 [CommandLine](IMPORTANT): Depth_Keller.offset=-4.87 decibar;

2018-10-23T23:50:54.957Z,1540338654.957 [CommandLine](IMPORTANT): Depth_Keller.scale=43.44 micropascal;

2018-10-23T23:50:54.957Z,1540338654.957 [CommandLine](IMPORTANT): Onboard.loadAtStartup=0 bool;

2018-10-23T23:50:54.962Z,1540338654.962 [CBIT](INFO): Critical error at 20181023T235054
2018-10-23T23:50:55.284Z,1540338655.284 [MissionManager](IMPORTANT): Started mission Startup
2018-10-23T23:50:55.284Z,1540338655.284 [Startup] Running Loop=1
2018-10-23T23:50:55.285Z,1540338655.285 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-23T23:50:55.285Z,1540338655.285 [Startup:A.GoToSurface] Running Loop=1
2018-10-23T23:50:55.285Z,1540338655.285 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-23T23:50:55.285Z,1540338655.285 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-23T23:50:55.286Z,1540338655.286 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-23T23:50:55.286Z,1540338655.286 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-23T23:50:55.287Z,1540338655.287 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-23T23:50:55.287Z,1540338655.287 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-23T23:50:55.446Z,1540338655.446 [Radio_Surface](INFO): Powering up
2018-10-23T23:51:09.379Z,1540338669.379 [CommandLine](IMPORTANT): got command configSet list
2018-10-23T23:51:09.379Z,1540338669.379 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-23T23:51:09.380Z,1540338669.380 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-10-23T23:51:09.380Z,1540338669.380 [CommandLine](IMPORTANT): Depth_Keller.offset=-4.87 decibar;

2018-10-23T23:51:09.380Z,1540338669.380 [CommandLine](IMPORTANT): Depth_Keller.scale=43.44 micropascal;

2018-10-23T23:51:09.380Z,1540338669.380 [CommandLine](IMPORTANT): Onboard.loadAtStartup=0 bool;

2018-10-23T23:51:32.888Z,1540338692.888 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:51:32.889Z,1540338692.889 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-10-23T23:51:32.889Z,1540338692.889 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-23T23:51:32.890Z,1540338692.890 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:51:32.890Z,1540338692.890 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-23T23:51:32.891Z,1540338692.891 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-23T23:51:32.966Z,1540338692.966 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-23T23:51:32.967Z,1540338692.967 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-23T23:51:33.298Z,1540338693.298 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-23T23:51:33.298Z,1540338693.298 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-10-23T23:51:33.298Z,1540338693.298 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-23T23:51:33.298Z,1540338693.298 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-23T23:51:33.684Z,1540338693.684 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-23T23:51:33.685Z,1540338693.685 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:51:33.685Z,1540338693.685 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:51:33.686Z,1540338693.686 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-23T23:51:33.686Z,1540338693.686 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:51:33.686Z,1540338693.686 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:51:40.939Z,1540338700.939 [CommandLine](IMPORTANT): got command configSet Depth_Keller.offset 0.000000 decibar
2018-10-23T23:52:01.807Z,1540338721.807 [CommandLine](IMPORTANT): got command report touch sea_water_pressure
2018-10-23T23:52:01.885Z,1540338721.885 [Reporter](INFO): sea_water_pressure 18.216903 db
2018-10-23T23:52:01.986Z,1540338721.986 [Reporter](INFO): sea_water_pressure 18.207717 db
2018-10-23T23:52:02.340Z,1540338722.340 [Reporter](INFO): sea_water_pressure 18.207717 db
2018-10-23T23:52:02.699Z,1540338722.699 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004416
2018-10-23T23:52:02.743Z,1540338722.743 [Reporter](INFO): sea_water_pressure 18.220347 db
2018-10-23T23:52:03.135Z,1540338723.135 [Reporter](INFO): sea_water_pressure 18.206570 db
2018-10-23T23:52:03.542Z,1540338723.542 [Reporter](INFO): sea_water_pressure 18.207334 db
2018-10-23T23:52:03.943Z,1540338723.943 [Reporter](INFO): sea_water_pressure 18.212311 db
2018-10-23T23:52:04.327Z,1540338724.327 [Reporter](INFO): sea_water_pressure 18.213841 db
2018-10-23T23:52:04.741Z,1540338724.741 [Reporter](INFO): sea_water_pressure 18.214989 db
2018-10-23T23:52:05.149Z,1540338725.149 [Reporter](INFO): sea_water_pressure 18.209631 db
2018-10-23T23:52:05.516Z,1540338725.516 [Reporter](INFO): sea_water_pressure 18.207334 db
2018-10-23T23:52:05.952Z,1540338725.952 [Reporter](INFO): sea_water_pressure 18.219581 db
2018-10-23T23:52:06.352Z,1540338726.352 [Reporter](INFO): sea_water_pressure 18.211928 db
2018-10-23T23:52:06.747Z,1540338726.747 [Reporter](INFO): sea_water_pressure 18.216138 db
2018-10-23T23:52:07.148Z,1540338727.148 [Reporter](INFO): sea_water_pressure 18.209631 db
2018-10-23T23:52:07.547Z,1540338727.547 [Reporter](INFO): sea_water_pressure 18.213841 db
2018-10-23T23:52:07.948Z,1540338727.948 [Reporter](INFO): sea_water_pressure 18.214223 db
2018-10-23T23:52:08.347Z,1540338728.347 [Reporter](INFO): sea_water_pressure 18.208102 db
2018-10-23T23:52:08.752Z,1540338728.752 [Reporter](INFO): sea_water_pressure 18.214223 db
2018-10-23T23:52:09.116Z,1540338729.116 [Reporter](INFO): sea_water_pressure 18.208866 db
2018-10-23T23:52:09.539Z,1540338729.539 [Reporter](INFO): sea_water_pressure 18.213459 db
2018-10-23T23:52:09.951Z,1540338729.951 [Reporter](INFO): sea_water_pressure 18.209250 db
2018-10-23T23:52:10.319Z,1540338730.319 [Reporter](INFO): sea_water_pressure 18.208102 db
2018-10-23T23:52:10.744Z,1540338730.744 [Reporter](INFO): sea_water_pressure 18.205805 db
2018-10-23T23:52:11.152Z,1540338731.152 [Reporter](INFO): sea_water_pressure 18.216138 db
2018-10-23T23:52:11.516Z,1540338731.516 [Reporter](INFO): sea_water_pressure 18.223409 db
2018-10-23T23:52:11.947Z,1540338731.947 [Reporter](INFO): sea_water_pressure 18.211545 db
2018-10-23T23:52:12.348Z,1540338732.348 [Reporter](INFO): sea_water_pressure 18.215372 db
2018-10-23T23:52:12.747Z,1540338732.747 [Reporter](INFO): sea_water_pressure 18.214989 db
2018-10-23T23:52:13.148Z,1540338733.148 [Reporter](INFO): sea_water_pressure 18.220730 db
2018-10-23T23:52:13.519Z,1540338733.519 [Reporter](INFO): sea_water_pressure 18.205041 db
2018-10-23T23:52:13.916Z,1540338733.916 [Reporter](INFO): sea_water_pressure 18.218817 db
2018-10-23T23:52:14.352Z,1540338734.352 [Reporter](INFO): sea_water_pressure 18.210398 db
2018-10-23T23:52:14.753Z,1540338734.753 [Reporter](INFO): sea_water_pressure 18.212692 db
2018-10-23T23:52:15.117Z,1540338735.117 [Reporter](INFO): sea_water_pressure 18.214608 db
2018-10-23T23:52:15.543Z,1540338735.543 [Reporter](INFO): sea_water_pressure 18.218433 db
2018-10-23T23:52:15.920Z,1540338735.920 [Reporter](INFO): sea_water_pressure 18.208866 db
2018-10-23T23:52:16.320Z,1540338736.320 [Reporter](INFO): sea_water_pressure 18.202744 db
2018-10-23T23:52:16.720Z,1540338736.720 [Reporter](INFO): sea_water_pressure 18.213075 db
2018-10-23T23:52:17.147Z,1540338737.147 [Reporter](INFO): sea_water_pressure 18.217284 db
2018-10-23T23:52:17.549Z,1540338737.549 [Reporter](INFO): sea_water_pressure 18.224175 db
2018-10-23T23:52:17.950Z,1540338737.950 [Reporter](INFO): sea_water_pressure 18.196238 db
2018-10-23T23:52:18.349Z,1540338738.349 [Reporter](INFO): sea_water_pressure 18.217284 db
2018-10-23T23:52:18.743Z,1540338738.743 [Reporter](INFO): sea_water_pressure 18.219966 db
2018-10-23T23:52:19.151Z,1540338739.151 [Reporter](INFO): sea_water_pressure 18.203892 db
2018-10-23T23:52:19.552Z,1540338739.552 [Reporter](INFO): sea_water_pressure 18.213841 db
2018-10-23T23:52:19.916Z,1540338739.916 [Reporter](INFO): sea_water_pressure 18.215755 db
2018-10-23T23:52:20.351Z,1540338740.351 [Reporter](INFO): sea_water_pressure 18.210014 db
2018-10-23T23:52:20.751Z,1540338740.751 [Reporter](INFO): sea_water_pressure 18.214989 db
2018-10-23T23:52:21.116Z,1540338741.116 [Reporter](INFO): sea_water_pressure 18.219581 db
2018-10-23T23:52:21.552Z,1540338741.552 [Reporter](INFO): sea_water_pressure 18.213841 db
2018-10-23T23:52:21.952Z,1540338741.952 [Reporter](INFO): sea_water_pressure 18.212311 db
2018-10-23T23:52:22.347Z,1540338742.347 [Reporter](INFO): sea_water_pressure 18.213459 db
2018-10-23T23:52:22.748Z,1540338742.748 [Reporter](INFO): sea_water_pressure 18.205805 db
2018-10-23T23:52:23.147Z,1540338743.147 [Reporter](INFO): sea_water_pressure 18.217669 db
2018-10-23T23:52:23.548Z,1540338743.548 [Reporter](INFO): sea_water_pressure 18.215755 db
2018-10-23T23:52:23.919Z,1540338743.919 [Reporter](INFO): sea_water_pressure 18.217669 db
2018-10-23T23:52:24.352Z,1540338744.352 [Reporter](INFO): sea_water_pressure 18.210398 db
2018-10-23T23:52:24.742Z,1540338744.742 [Reporter](INFO): sea_water_pressure 18.208102 db
2018-10-23T23:52:25.150Z,1540338745.150 [Reporter](INFO): sea_water_pressure 18.212311 db
2018-10-23T23:52:25.550Z,1540338745.550 [Reporter](INFO): sea_water_pressure 18.203125 db
2018-10-23T23:52:25.919Z,1540338745.919 [Reporter](INFO): sea_water_pressure 18.211163 db
2018-10-23T23:52:26.351Z,1540338746.351 [Reporter](INFO): sea_water_pressure 18.201977 db
2018-10-23T23:52:26.750Z,1540338746.750 [Reporter](INFO): sea_water_pressure 18.210398 db
2018-10-23T23:52:27.116Z,1540338747.116 [Reporter](INFO): sea_water_pressure 18.220347 db
2018-10-23T23:52:27.547Z,1540338747.547 [Reporter](INFO): sea_water_pressure 18.217669 db
2018-10-23T23:52:27.949Z,1540338747.949 [Reporter](INFO): sea_water_pressure 18.212311 db
2018-10-23T23:52:28.349Z,1540338748.349 [Reporter](INFO): sea_water_pressure 18.214989 db
2018-10-23T23:52:28.748Z,1540338748.748 [Reporter](INFO): sea_water_pressure 18.209250 db
2018-10-23T23:52:29.151Z,1540338749.151 [Reporter](INFO): sea_water_pressure 18.207334 db
2018-10-23T23:52:29.516Z,1540338749.516 [Reporter](INFO): sea_water_pressure 18.214989 db
2018-10-23T23:52:29.950Z,1540338749.950 [Reporter](INFO): sea_water_pressure 18.214989 db
2018-10-23T23:52:30.351Z,1540338750.351 [Reporter](INFO): sea_water_pressure 18.216138 db
2018-10-23T23:52:30.743Z,1540338750.743 [Reporter](INFO): sea_water_pressure 18.206953 db
2018-10-23T23:52:31.151Z,1540338751.151 [Reporter](INFO): sea_water_pressure 18.212692 db
2018-10-23T23:52:31.552Z,1540338751.552 [Reporter](INFO): sea_water_pressure 18.214223 db
2018-10-23T23:52:31.916Z,1540338751.916 [Reporter](INFO): sea_water_pressure 18.223409 db
2018-10-23T23:52:32.347Z,1540338752.347 [Reporter](INFO): sea_water_pressure 18.216520 db
2018-10-23T23:52:32.747Z,1540338752.747 [Reporter](INFO): sea_water_pressure 18.215372 db
2018-10-23T23:52:33.148Z,1540338753.148 [Reporter](INFO): sea_water_pressure 18.216903 db
2018-10-23T23:52:33.547Z,1540338753.547 [Reporter](INFO): sea_water_pressure 18.211163 db
2018-10-23T23:52:36.652Z,1540338756.652 [Reporter](INFO): sea_water_pressure 18.211928 db
2018-10-23T23:52:36.728Z,1540338756.728 [Reporter](INFO): sea_water_pressure 18.212692 db
2018-10-23T23:52:37.104Z,1540338757.104 [Reporter](INFO): sea_water_pressure 18.217284 db
2018-10-23T23:52:37.506Z,1540338757.506 [Reporter](INFO): sea_water_pressure 18.206188 db
2018-10-23T23:52:37.908Z,1540338757.908 [Reporter](INFO): sea_water_pressure 18.217669 db
2018-10-23T23:52:38.313Z,1540338758.313 [Reporter](INFO): sea_water_pressure 18.197767 db
2018-10-23T23:52:38.426Z,1540338758.426 [CommandLine](IMPORTANT): got command report clear
2018-10-23T23:52:48.935Z,1540338768.935 [CommandLine](IMPORTANT): got command get sea_water_pressure pound_per_square_inch
2018-10-23T23:52:48.935Z,1540338768.935 [CommandLine](IMPORTANT): sea_water_pressure 26.416389 psi
2018-10-23T23:53:33.809Z,1540338813.809 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:53:33.809Z,1540338813.809 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-10-23T23:53:33.809Z,1540338813.809 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-23T23:53:33.811Z,1540338813.811 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:53:33.811Z,1540338813.811 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-23T23:53:33.811Z,1540338813.811 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-23T23:53:33.873Z,1540338813.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-23T23:53:33.873Z,1540338813.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-23T23:53:34.220Z,1540338814.220 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-23T23:53:34.220Z,1540338814.220 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-10-23T23:53:34.220Z,1540338814.220 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-23T23:53:34.220Z,1540338814.220 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-23T23:53:34.605Z,1540338814.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-23T23:53:34.605Z,1540338814.605 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:53:34.605Z,1540338814.605 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:53:34.606Z,1540338814.606 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-23T23:53:34.606Z,1540338814.606 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:53:34.607Z,1540338814.607 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:53:54.646Z,1540338834.646 [CommandLine](IMPORTANT): got command get sea_water_pressure
2018-10-23T23:53:54.647Z,1540338834.647 [CommandLine](IMPORTANT): sea_water_pressure 18.219198 db
2018-10-23T23:54:16.143Z,1540338856.143 [CommandLine](IMPORTANT): got command configSet Depth_Keller.offset -18.200001 decibar
2018-10-23T23:54:16.530Z,1540338856.530 [Startup:StartupSatComms] Running Loop=1
2018-10-23T23:54:16.531Z,1540338856.531 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-23T23:54:16.531Z,1540338856.531 [Startup:StartupSatComms:A] Running Loop=1
2018-10-23T23:54:16.936Z,1540338856.936 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-23T23:54:31.500Z,1540338871.500 [CommandLine](IMPORTANT): got command get sea_water_pressure
2018-10-23T23:54:31.500Z,1540338871.500 [CommandLine](IMPORTANT): sea_water_pressure 0.022261 db
2018-10-23T23:54:40.135Z,1540338880.135 [CommandLine](IMPORTANT): got command get sea_water_pressure pound_per_square_inch
2018-10-23T23:54:40.135Z,1540338880.135 [CommandLine](IMPORTANT): sea_water_pressure 0.047272 psi
2018-10-23T23:55:12.694Z,1540338912.694 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-10-23T23:55:14.947Z,1540338914.947 [CommandLine](IMPORTANT): got command get sea_water_pressure pound_per_square_inch
2018-10-23T23:55:14.947Z,1540338914.947 [CommandLine](IMPORTANT): sea_water_pressure 13.980929 psi
2018-10-23T23:55:16.720Z,1540338916.720 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-23T23:54:16.5Z
2018-10-23T23:55:16.720Z,1540338916.720 [Startup:StartupSatComms:A] Stopped
2018-10-23T23:55:16.720Z,1540338916.720 [Startup:StartupSatComms:B] Running Loop=1
2018-10-23T23:55:17.120Z,1540338917.120 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-23T23:55:21.885Z,1540338921.885 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20181023T230024/Courier0013.lzma
2018-10-23T23:55:22.679Z,1540338922.679 [DataOverHttps](INFO): Moved sent file to Logs/20181023T230024/Courier0013.lzma.bak
2018-10-23T23:55:22.679Z,1540338922.679 [DataOverHttps](INFO): SBD MOMSN=8747952
2018-10-23T23:55:27.416Z,1540338927.416 [VerticalControl](CRITICAL): Excessive depth excursion=22.979900 m, failToGoUpDepth_=8.701716 m, depthRate=1.855983 m/s, pitch=nan deg.
2018-10-23T23:55:27.478Z,1540338927.478 [CBIT](INFO): Critical error at 20181023T235527
2018-10-23T23:55:27.574Z,1540338927.574 [CommandLine](IMPORTANT): got command get sea_water_pressure pound_per_square_inch
2018-10-23T23:55:27.575Z,1540338927.575 [CommandLine](IMPORTANT): sea_water_pressure 68.813581 psi
2018-10-23T23:55:32.993Z,1540338932.993 [VerticalControl](CRITICAL): Dropping drop weight at excessive depth excursion=38.420815, failToGoUpDepth_=8.701716, depthRate=3.418534 m/s, pitch=nan deg.
2018-10-23T23:55:33.060Z,1540338933.060 [CBIT](INFO): Critical error at 20181023T235532
2018-10-23T23:55:33.074Z,1540338933.074 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20181023T233139/Courier0000.lzma
2018-10-23T23:55:33.871Z,1540338933.871 [DataOverHttps](INFO): Moved sent file to Logs/20181023T233139/Courier0000.lzma.bak
2018-10-23T23:55:33.871Z,1540338933.871 [DataOverHttps](INFO): SBD MOMSN=8747956
2018-10-23T23:55:34.684Z,1540338934.684 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:55:34.684Z,1540338934.684 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-10-23T23:55:34.684Z,1540338934.684 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-23T23:55:34.686Z,1540338934.686 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:55:34.686Z,1540338934.686 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-10-23T23:55:34.686Z,1540338934.686 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-23T23:55:34.701Z,1540338934.701 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-23T23:55:34.701Z,1540338934.701 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-23T23:55:35.093Z,1540338935.093 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-23T23:55:35.093Z,1540338935.093 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-10-23T23:55:35.093Z,1540338935.093 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-23T23:55:35.093Z,1540338935.093 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-10-23T23:55:35.480Z,1540338935.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-23T23:55:35.481Z,1540338935.481 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:55:35.482Z,1540338935.482 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:55:35.482Z,1540338935.482 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-23T23:55:35.482Z,1540338935.482 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:55:35.483Z,1540338935.483 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:55:44.129Z,1540338944.129 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:55:44.129Z,1540338944.129 [AHRS_M2] No Fault, FailCount= 5
2018-10-23T23:55:44.136Z,1540338944.136 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:55:44.150Z,1540338944.150 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20181023T233507/Courier0000.lzma
2018-10-23T23:55:44.939Z,1540338944.939 [DataOverHttps](INFO): Moved sent file to Logs/20181023T233507/Courier0000.lzma.bak
2018-10-23T23:55:44.939Z,1540338944.939 [DataOverHttps](INFO): SBD MOMSN=8747963
2018-10-23T23:55:49.601Z,1540338949.601 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:50.013Z,1540338950.013 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:50.413Z,1540338950.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:50.813Z,1540338950.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:51.213Z,1540338951.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:51.613Z,1540338951.613 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:52.017Z,1540338952.017 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:52.425Z,1540338952.425 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:55.513Z,1540338955.513 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:55:55.513Z,1540338955.513 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:55:55.513Z,1540338955.513 [AHRS_M2] Communications Fault, FailCount= 1
2018-10-23T23:55:55.513Z,1540338955.513 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:55:55.519Z,1540338955.519 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20181023T233959/Courier0000.lzma
2018-10-23T23:55:55.543Z,1540338955.543 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:55:55.593Z,1540338955.593 [AHRS_M2](INFO): Powering down
2018-10-23T23:55:55.983Z,1540338955.983 [DataOverHttps](INFO): Moved sent file to Logs/20181023T233959/Courier0000.lzma.bak
2018-10-23T23:55:55.983Z,1540338955.983 [DataOverHttps](INFO): SBD MOMSN=8747970
2018-10-23T23:56:01.342Z,1540338961.342 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:56:01.342Z,1540338961.342 [AHRS_M2] No Fault, FailCount= 1
2018-10-23T23:56:01.705Z,1540338961.705 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:56:06.773Z,1540338966.773 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:06.777Z,1540338966.777 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181023T233959/Courier0004.lzma
2018-10-23T23:56:07.177Z,1540338967.177 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:07.582Z,1540338967.582 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:07.584Z,1540338967.584 [DataOverHttps](INFO): Moved sent file to Logs/20181023T233959/Courier0004.lzma.bak
2018-10-23T23:56:07.584Z,1540338967.584 [DataOverHttps](INFO): SBD MOMSN=8747977
2018-10-23T23:56:07.985Z,1540338967.985 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:08.413Z,1540338968.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:08.817Z,1540338968.817 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:11.539Z,1540338971.539 [CommandLine](IMPORTANT): got command get sea_water_pressure pound_per_square_inch
2018-10-23T23:56:11.539Z,1540338971.539 [CommandLine](IMPORTANT): sea_water_pressure 66.210906 psi
2018-10-23T23:56:12.413Z,1540338972.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:12.413Z,1540338972.413 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:56:12.413Z,1540338972.413 [AHRS_M2] Communications Fault, FailCount= 2
2018-10-23T23:56:12.413Z,1540338972.413 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:56:12.483Z,1540338972.483 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:56:12.490Z,1540338972.490 [AHRS_M2](INFO): Powering down
2018-10-23T23:56:17.605Z,1540338977.605 [Startup:StartupSatComms:B](INFO): Timed out from 2018-10-23T23:55:16.7Z
2018-10-23T23:56:17.605Z,1540338977.605 [Startup:StartupSatComms:B] Stopped
2018-10-23T23:56:17.605Z,1540338977.605 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-23T23:56:17.605Z,1540338977.605 [Startup:StartupSatComms] Stopped
2018-10-23T23:56:17.606Z,1540338977.606 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-23T23:56:17.607Z,1540338977.607 [Startup](INFO): Completed Startup
2018-10-23T23:56:17.607Z,1540338977.607 [MissionManager](INFO): Startup is completed.
2018-10-23T23:56:17.607Z,1540338977.607 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-23T23:56:17.607Z,1540338977.607 [Startup] Stopped
2018-10-23T23:56:17.607Z,1540338977.607 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-23T23:56:17.607Z,1540338977.607 [Startup:A.GoToSurface] Stopped
2018-10-23T23:56:17.608Z,1540338977.608 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-23T23:56:17.631Z,1540338977.631 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20181023T234921/Courier0000.lzma
2018-10-23T23:56:17.707Z,1540338977.707 [MissionManager](IMPORTANT): Started mission Default
2018-10-23T23:56:17.707Z,1540338977.707 [Default] Running Loop=1
2018-10-23T23:56:17.708Z,1540338977.708 [Default](DEBUG): Aggregate::initialize Default
2018-10-23T23:56:17.708Z,1540338977.708 [Default:B.GoToSurface] Running Loop=1
2018-10-23T23:56:17.708Z,1540338977.708 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-23T23:56:17.708Z,1540338977.708 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-23T23:56:17.708Z,1540338977.708 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-23T23:56:17.713Z,1540338977.713 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-23T23:56:17.713Z,1540338977.713 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-23T23:56:17.714Z,1540338977.714 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-23T23:56:17.714Z,1540338977.714 [Default:A.Wait] Running Loop=1
2018-10-23T23:56:17.714Z,1540338977.714 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-23T23:56:18.055Z,1540338978.055 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:56:18.055Z,1540338978.055 [AHRS_M2] No Fault, FailCount= 2
2018-10-23T23:56:18.429Z,1540338978.429 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:56:18.439Z,1540338978.439 [DataOverHttps](INFO): Moved sent file to Logs/20181023T234921/Courier0000.lzma.bak
2018-10-23T23:56:18.439Z,1540338978.439 [DataOverHttps](INFO): SBD MOMSN=8747979
2018-10-23T23:56:23.885Z,1540338983.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:24.273Z,1540338984.273 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:24.673Z,1540338984.673 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:25.077Z,1540338985.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:25.481Z,1540338985.481 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:25.881Z,1540338985.881 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:26.281Z,1540338986.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:26.681Z,1540338986.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:27.081Z,1540338987.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:27.471Z,1540338987.471 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:27.871Z,1540338987.871 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:28.285Z,1540338988.285 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:28.670Z,1540338988.670 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:28.670Z,1540338988.670 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:56:28.670Z,1540338988.670 [AHRS_M2] Communications Fault, FailCount= 3
2018-10-23T23:56:28.670Z,1540338988.670 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:56:28.693Z,1540338988.693 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:56:28.757Z,1540338988.757 [AHRS_M2](INFO): Powering down
2018-10-23T23:56:30.789Z,1540338990.789 [Default:A.Wait](INFO): Done Waiting.
2018-10-23T23:56:30.789Z,1540338990.789 [Default:A.Wait] Stopped
2018-10-23T23:56:30.789Z,1540338990.789 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-10-23T23:56:32.394Z,1540338992.394 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:56:32.394Z,1540338992.394 [AHRS_M2] No Fault, FailCount= 3
2018-10-23T23:56:32.757Z,1540338992.757 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:56:36.285Z,1540338996.285 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:36.681Z,1540338996.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:37.089Z,1540338997.089 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:37.489Z,1540338997.489 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:37.889Z,1540338997.889 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:38.285Z,1540338998.285 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:38.685Z,1540338998.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:39.085Z,1540338999.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:39.485Z,1540338999.485 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:39.885Z,1540338999.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:40.289Z,1540339000.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:40.685Z,1540339000.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:41.085Z,1540339001.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:41.485Z,1540339001.485 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:41.885Z,1540339001.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:42.285Z,1540339002.285 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:42.689Z,1540339002.689 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:43.085Z,1540339003.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:43.085Z,1540339003.085 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:56:43.085Z,1540339003.085 [AHRS_M2] Communications Fault, FailCount= 4
2018-10-23T23:56:43.085Z,1540339003.085 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:56:43.119Z,1540339003.119 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:56:43.161Z,1540339003.161 [AHRS_M2](INFO): Powering down
2018-10-23T23:56:46.813Z,1540339006.813 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-10-23T23:56:46.813Z,1540339006.813 [AHRS_M2] No Fault, FailCount= 4
2018-10-23T23:56:47.165Z,1540339007.165 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-23T23:56:50.685Z,1540339010.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:51.085Z,1540339011.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:51.493Z,1540339011.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:51.885Z,1540339011.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:52.285Z,1540339012.285 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:52.693Z,1540339012.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:53.097Z,1540339013.097 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:53.493Z,1540339013.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:53.909Z,1540339013.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:54.309Z,1540339014.309 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:54.709Z,1540339014.709 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:55.109Z,1540339015.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:55.509Z,1540339015.509 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:58.573Z,1540339018.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-10-23T23:56:58.573Z,1540339018.573 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-10-23T23:56:58.573Z,1540339018.573 [AHRS_M2] Communications Fault, FailCount= 5
2018-10-23T23:56:58.573Z,1540339018.573 [AHRS_M2](ERROR): Communications Fault
2018-10-23T23:56:58.636Z,1540339018.636 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-10-23T23:56:58.636Z,1540339018.636 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2018-10-23T23:56:58.654Z,1540339018.654 [AHRS_M2](INFO): Powering down
2018-10-23T23:56:58.778Z,1540339018.778 [CBIT](INFO): Critical error at 20181023T235658
2018-10-23T23:57:11.084Z,1540339031.084 [VerticalControl](CRITICAL): Excessive depth excursion=33.984451 m, failToGoUpDepth_=47.615883 m, depthRate=1.207366 m/s, pitch=nan deg.
2018-10-23T23:57:11.090Z,1540339031.090 [CBIT](INFO): Critical error at 20181023T235711
2018-10-23T23:57:16.292Z,1540339036.292 [VerticalControl](CRITICAL): Dropping drop weight at excessive depth excursion=36.689964, failToGoUpDepth_=47.615883, depthRate=1.300688 m/s, pitch=nan deg.
2018-10-23T23:57:16.315Z,1540339036.315 [CBIT](INFO): Critical error at 20181023T235716
2018-10-23T23:57:19.182Z,1540339039.182 [CommandLine](IMPORTANT): got command get sea_water_pressure pound_per_square_inch
2018-10-23T23:57:19.182Z,1540339039.182 [CommandLine](IMPORTANT): sea_water_pressure 122.113717 psi
2018-10-23T23:57:31.202Z,1540339051.202 [CommandLine](IMPORTANT): got command get sea_water_pressure pound_per_square_inch
2018-10-23T23:57:31.202Z,1540339051.202 [CommandLine](IMPORTANT): sea_water_pressure 121.757939 psi
2018-10-23T23:57:35.823Z,1540339055.823 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:57:35.823Z,1540339055.823 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-10-23T23:57:35.823Z,1540339055.823 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-23T23:57:35.825Z,1540339055.825 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:57:35.825Z,1540339055.825 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-10-23T23:57:35.825Z,1540339055.825 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-23T23:57:35.839Z,1540339055.839 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-23T23:57:35.839Z,1540339055.839 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-23T23:57:36.242Z,1540339056.242 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-23T23:57:36.242Z,1540339056.242 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-10-23T23:57:36.243Z,1540339056.243 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-23T23:57:36.243Z,1540339056.243 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-10-23T23:57:36.632Z,1540339056.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-23T23:57:36.633Z,1540339056.633 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:57:36.633Z,1540339056.633 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:57:36.634Z,1540339056.634 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-23T23:57:36.634Z,1540339056.634 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:57:36.634Z,1540339056.634 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:57:59.038Z,1540339079.038 [Radio_Surface](INFO): Powering down
2018-10-23T23:58:05.106Z,1540339085.106 [Radio_Surface](INFO): Powering up
2018-10-23T23:58:34.202Z,1540339114.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-23T23:58:34.202Z,1540339114.202 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.000149
2018-10-23T23:59:02.619Z,1540339142.619 [Default:CheckIn] Running Loop=1
2018-10-23T23:59:02.620Z,1540339142.620 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-23T23:59:02.620Z,1540339142.620 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-23T23:59:03.044Z,1540339143.044 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-10-23T23:59:09.436Z,1540339149.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-23T23:59:19.915Z,1540339159.915 [CommandLine](IMPORTANT): got command quit
2018-10-23T23:59:20.924Z,1540339160.924 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-23T23:59:20.924Z,1540339160.924 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:20.937Z,1540339160.937 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-23T23:59:20.937Z,1540339160.937 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:20.938Z,1540339160.938 [CommandLine](INFO): Join timeout helper Thread ID is 939
2018-10-23T23:59:20.943Z,1540339160.943 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-23T23:59:20.943Z,1540339160.943 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:20.943Z,1540339160.943 [NavChartDb](INFO): Join timeout helper Thread ID is 940
2018-10-23T23:59:21.053Z,1540339161.053 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-23T23:59:21.053Z,1540339161.053 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.061Z,1540339161.061 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-23T23:59:21.061Z,1540339161.061 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.061Z,1540339161.061 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 941
2018-10-23T23:59:21.273Z,1540339161.273 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-23T23:59:21.273Z,1540339161.273 [WetLabsBB2FL](INFO): Powering down
2018-10-23T23:59:21.274Z,1540339161.274 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.289Z,1540339161.289 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-10-23T23:59:21.289Z,1540339161.289 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.289Z,1540339161.289 [CTD_Seabird](INFO): Join timeout helper Thread ID is 942
2018-10-23T23:59:21.653Z,1540339161.653 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-23T23:59:21.740Z,1540339161.740 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-23T23:59:21.740Z,1540339161.740 [CTD_Seabird](INFO): Powering down
2018-10-23T23:59:21.742Z,1540339161.742 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.749Z,1540339161.749 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-10-23T23:59:21.749Z,1540339161.749 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.750Z,1540339161.750 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 944
2018-10-23T23:59:21.929Z,1540339161.929 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-23T23:59:21.929Z,1540339161.929 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.938Z,1540339161.938 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-23T23:59:21.938Z,1540339161.938 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.938Z,1540339161.938 [Radio_Surface](INFO): Join timeout helper Thread ID is 945
2018-10-23T23:59:21.941Z,1540339161.941 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-23T23:59:21.942Z,1540339161.942 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.958Z,1540339161.958 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-23T23:59:21.958Z,1540339161.958 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.958Z,1540339161.958 [logger](INFO): Join timeout helper Thread ID is 946
2018-10-23T23:59:21.981Z,1540339161.981 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-23T23:59:21.981Z,1540339161.981 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.998Z,1540339161.998 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-23T23:59:21.998Z,1540339161.998 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.998Z,1540339161.998 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-23T23:59:21.998Z,1540339161.998 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:21.998Z,1540339161.998 [controlThread](INFO): Join timeout helper Thread ID is 947
2018-10-23T23:59:22.229Z,1540339162.229 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-23T23:59:22.229Z,1540339162.229 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-23T23:59:22.230Z,1540339162.230 [AHRS_M2](INFO): Powering down
2018-10-23T23:59:22.231Z,1540339162.231 [NAL9602](INFO): Powering down
2018-10-23T23:59:22.233Z,1540339162.233 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-23T23:59:22.234Z,1540339162.234 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-23T23:59:22.234Z,1540339162.234 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-23T23:59:22.235Z,1540339162.235 [MissionManager](INFO): Uninitializing Mission Default
2018-10-23T23:59:22.235Z,1540339162.235 [Default] Stopped
2018-10-23T23:59:22.235Z,1540339162.235 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-23T23:59:22.235Z,1540339162.235 [Default:B.GoToSurface] Stopped
2018-10-23T23:59:22.235Z,1540339162.235 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-23T23:59:22.235Z,1540339162.235 [Default:CheckIn] Stopped
2018-10-23T23:59:22.236Z,1540339162.236 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-23T23:59:22.236Z,1540339162.236 [Default:CheckIn:Read_GPS] Stopped
2018-10-23T23:59:22.239Z,1540339162.239 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-23T23:59:22.239Z,1540339162.239 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-23T23:59:22.240Z,1540339162.240 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-23T23:59:22.240Z,1540339162.240 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-23T23:59:22.240Z,1540339162.240 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-23T23:59:22.240Z,1540339162.240 [BuoyancyServo](INFO): Powering down
2018-10-23T23:59:22.253Z,1540339162.253 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-23T23:59:22.253Z,1540339162.253 [ElevatorServo](INFO): Powering down
2018-10-23T23:59:22.254Z,1540339162.254 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-23T23:59:22.254Z,1540339162.254 [MassServo](INFO): Powering down
2018-10-23T23:59:22.255Z,1540339162.255 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-23T23:59:22.255Z,1540339162.255 [RudderServo](INFO): Powering down
2018-10-23T23:59:22.256Z,1540339162.256 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-23T23:59:22.256Z,1540339162.256 [ThrusterServo](INFO): Powering down
2018-10-23T23:59:22.258Z,1540339162.258 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-23T23:59:22.258Z,1540339162.258 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-23T23:59:22.258Z,1540339162.258 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-23T23:59:22.259Z,1540339162.259 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:22.347Z,1540339162.347 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:22.388Z,1540339162.388 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:22.391Z,1540339162.391 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:22.394Z,1540339162.394 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:22.560Z,1540339162.560 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-23T23:59:22.616Z,1540339162.616 [logger ThreadHandler](INFO): Thread cancelled.