2019-02-21T20:40:53.663Z,1550781653.663 [Supervisor](DEBUG): Initializing supervisor.
2019-02-21T20:40:53.666Z,1550781653.666 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T20:40:53.667Z,1550781653.667 [SyncHandler](INFO): Protected caller Thread ID is 945
2019-02-21T20:40:53.667Z,1550781653.667 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-21T20:40:53.668Z,1550781653.668 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-21T20:40:53.668Z,1550781653.668 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 946
2019-02-21T20:40:53.671Z,1550781653.671 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-21T20:40:53.718Z,1550781653.718 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-21T20:40:53.719Z,1550781653.719 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-21T20:40:53.719Z,1550781653.719 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 947
2019-02-21T20:40:53.720Z,1550781653.720 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-21T20:40:53.721Z,1550781653.721 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-21T20:40:53.721Z,1550781653.721 [logger ThreadHandler](INFO): Protected caller Thread ID is 948
2019-02-21T20:40:53.723Z,1550781653.723 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-21T20:40:53.723Z,1550781653.723 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-21T20:40:53.725Z,1550781653.725 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-21T20:40:54.143Z,1550781654.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-21T20:40:54.143Z,1550781654.143 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-21T20:40:54.247Z,1550781654.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-21T20:40:54.247Z,1550781654.247 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-21T20:40:54.580Z,1550781654.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-21T20:40:54.580Z,1550781654.580 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-21T20:40:54.721Z,1550781654.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-21T20:40:54.722Z,1550781654.722 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-21T20:40:54.916Z,1550781654.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-21T20:40:54.917Z,1550781654.917 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-21T20:40:55.383Z,1550781655.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-21T20:40:55.384Z,1550781655.384 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-21T20:40:55.591Z,1550781655.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-21T20:40:55.592Z,1550781655.592 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-21T20:40:55.739Z,1550781655.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-21T20:40:55.740Z,1550781655.740 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-21T20:40:55.938Z,1550781655.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-21T20:40:55.938Z,1550781655.938 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-21T20:40:56.036Z,1550781656.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-21T20:40:56.037Z,1550781656.037 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-21T20:40:56.353Z,1550781656.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-21T20:40:56.354Z,1550781656.354 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-21T20:40:56.436Z,1550781656.436 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-21T20:40:56.542Z,1550781656.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-21T20:40:56.542Z,1550781656.542 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-21T20:40:57.037Z,1550781657.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-21T20:40:57.038Z,1550781657.038 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-21T20:40:57.438Z,1550781657.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-21T20:40:57.440Z,1550781657.440 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-21T20:40:57.441Z,1550781657.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-21T20:40:57.652Z,1550781657.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-21T20:40:57.754Z,1550781657.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-21T20:40:57.855Z,1550781657.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-21T20:40:58.084Z,1550781658.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-21T20:40:58.084Z,1550781658.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-21T20:40:58.171Z,1550781658.171 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-21T20:40:58.266Z,1550781658.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-21T20:40:58.366Z,1550781658.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-21T20:40:58.451Z,1550781658.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-21T20:40:58.561Z,1550781658.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-21T20:40:58.734Z,1550781658.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-21T20:40:58.869Z,1550781658.869 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/LOGIN/
2019-02-21T20:40:58.869Z,1550781658.869 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-21T20:40:58.871Z,1550781658.871 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-21T20:40:59.180Z,1550781659.180 [DataOverHttps] Loaded
2019-02-21T20:40:59.180Z,1550781659.180 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-21T20:40:59.220Z,1550781659.220 [Depth_Keller] Loaded
2019-02-21T20:40:59.220Z,1550781659.220 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-21T20:40:59.225Z,1550781659.225 [DropWeight] Loaded
2019-02-21T20:40:59.225Z,1550781659.225 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-21T20:40:59.273Z,1550781659.273 [DUSBL_Hydroid] Loaded
2019-02-21T20:40:59.273Z,1550781659.273 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-21T20:40:59.313Z,1550781659.313 [Micromodem] Loaded
2019-02-21T20:40:59.313Z,1550781659.313 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-02-21T20:40:59.456Z,1550781659.456 [NAL9602] Loaded
2019-02-21T20:40:59.468Z,1550781659.468 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-21T20:40:59.583Z,1550781659.583 [Onboard] Loaded
2019-02-21T20:40:59.584Z,1550781659.584 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-21T20:40:59.665Z,1550781659.665 [Radio_Surface] Loaded
2019-02-21T20:40:59.666Z,1550781659.666 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-21T20:40:59.667Z,1550781659.667 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407534E0
2019-02-21T20:40:59.667Z,1550781659.667 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1027
2019-02-21T20:40:59.842Z,1550781659.842 [RDI_Pathfinder] Loaded
2019-02-21T20:40:59.842Z,1550781659.842 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-21T20:40:59.924Z,1550781659.924 [RDI_PathfinderUp] Loaded
2019-02-21T20:40:59.924Z,1550781659.924 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-21T20:41:02.071Z,1550781662.071 [BPC1] Loaded
2019-02-21T20:41:02.072Z,1550781662.072 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-21T20:41:02.072Z,1550781662.072 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-21T20:41:02.073Z,1550781662.073 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-21T20:41:02.086Z,1550781662.086 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-21T20:41:02.086Z,1550781662.086 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-21T20:41:02.192Z,1550781662.192 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-21T20:41:02.192Z,1550781662.192 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-21T20:41:02.251Z,1550781662.251 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-21T20:41:02.251Z,1550781662.251 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-21T20:41:02.310Z,1550781662.310 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-21T20:41:02.310Z,1550781662.310 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-21T20:41:02.326Z,1550781662.326 [NavChart] Loaded
2019-02-21T20:41:02.326Z,1550781662.326 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-21T20:41:02.330Z,1550781662.330 [UniversalFixResidualReporter] Loaded
2019-02-21T20:41:02.331Z,1550781662.331 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-21T20:41:02.331Z,1550781662.331 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-21T20:41:02.332Z,1550781662.332 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-21T20:41:02.435Z,1550781662.435 [BuoyancyServo] Loaded
2019-02-21T20:41:02.435Z,1550781662.435 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-21T20:41:02.450Z,1550781662.450 [ElevatorServo] Loaded
2019-02-21T20:41:02.450Z,1550781662.450 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-21T20:41:02.465Z,1550781662.465 [MassServo] Loaded
2019-02-21T20:41:02.466Z,1550781662.466 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-21T20:41:02.481Z,1550781662.481 [RudderServo] Loaded
2019-02-21T20:41:02.481Z,1550781662.481 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-21T20:41:02.495Z,1550781662.495 [ThrusterServo] Loaded
2019-02-21T20:41:02.496Z,1550781662.496 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-21T20:41:02.496Z,1550781662.496 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-21T20:41:02.497Z,1550781662.497 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-21T20:41:02.675Z,1550781662.675 [Aanderaa_O2] Loaded
2019-02-21T20:41:02.675Z,1550781662.675 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-21T20:41:02.759Z,1550781662.759 [CTD_NeilBrown] Loaded
2019-02-21T20:41:02.759Z,1550781662.759 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-21T20:41:02.760Z,1550781662.760 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-21T20:41:02.760Z,1550781662.760 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1028
2019-02-21T20:41:02.805Z,1550781662.805 [WetLabsSeaOWL_UV_A] Loaded
2019-02-21T20:41:02.805Z,1550781662.805 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-21T20:41:02.806Z,1550781662.806 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-21T20:41:02.807Z,1550781662.807 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1029
2019-02-21T20:41:02.807Z,1550781662.807 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-21T20:41:02.808Z,1550781662.808 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-21T20:41:03.048Z,1550781663.048 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-21T20:41:03.049Z,1550781663.049 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-21T20:41:03.090Z,1550781663.090 [DepthRateCalculator] Loaded
2019-02-21T20:41:03.090Z,1550781663.090 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-21T20:41:03.096Z,1550781663.096 [PitchRateCalculator] Loaded
2019-02-21T20:41:03.097Z,1550781663.097 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-21T20:41:03.109Z,1550781663.109 [SpeedCalculator] Loaded
2019-02-21T20:41:03.109Z,1550781663.109 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-21T20:41:03.130Z,1550781663.130 [TempGradientCalculator] Loaded
2019-02-21T20:41:03.130Z,1550781663.130 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-21T20:41:03.136Z,1550781663.136 [YawRateCalculator] Loaded
2019-02-21T20:41:03.136Z,1550781663.136 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-21T20:41:03.176Z,1550781663.175 [ElevatorOffsetCalculator] Loaded
2019-02-21T20:41:03.176Z,1550781663.176 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-21T20:41:03.176Z,1550781663.176 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-21T20:41:03.177Z,1550781663.177 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-21T20:41:03.313Z,1550781663.313 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-21T20:41:03.335Z,1550781663.335 [SBIT] Loaded
2019-02-21T20:41:03.335Z,1550781663.335 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-21T20:41:03.336Z,1550781663.336 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-21T20:41:03.347Z,1550781663.347 [IBIT] Loaded
2019-02-21T20:41:03.347Z,1550781663.347 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-21T20:41:03.350Z,1550781663.350 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-21T20:41:03.489Z,1550781663.489 [CBIT] Loaded
2019-02-21T20:41:03.490Z,1550781663.490 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-21T20:41:03.490Z,1550781663.490 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-21T20:41:03.491Z,1550781663.491 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-21T20:41:03.554Z,1550781663.554 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-21T20:41:03.555Z,1550781663.555 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-21T20:41:03.652Z,1550781663.652 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-21T20:41:03.652Z,1550781663.652 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-21T20:41:03.721Z,1550781663.721 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-21T20:41:03.803Z,1550781663.803 [VerticalControl] Loaded
2019-02-21T20:41:03.804Z,1550781663.804 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-21T20:41:03.804Z,1550781663.804 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-21T20:41:03.863Z,1550781663.863 [HorizontalControl] Loaded
2019-02-21T20:41:03.863Z,1550781663.863 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-21T20:41:03.863Z,1550781663.863 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-21T20:41:03.865Z,1550781663.865 [SpeedControl] Loaded
2019-02-21T20:41:03.865Z,1550781663.865 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-21T20:41:03.866Z,1550781663.866 [LoopControl](DEBUG): Construct LoopControl.
2019-02-21T20:41:03.867Z,1550781663.867 [LoopControl] Loaded
2019-02-21T20:41:03.867Z,1550781663.867 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-21T20:41:03.867Z,1550781663.867 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-21T20:41:03.868Z,1550781663.868 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-21T20:41:03.895Z,1550781663.895 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-21T20:41:03.899Z,1550781663.899 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-21T20:41:03.900Z,1550781663.900 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-21T20:41:03.907Z,1550781663.907 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-21T20:41:03.908Z,1550781663.908 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0
2019-02-21T20:41:03.908Z,1550781663.908 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1030
2019-02-21T20:41:03.913Z,1550781663.913 [Supervisor](INFO): Main Thread ID is 944
2019-02-21T20:41:03.913Z,1550781663.913 [Supervisor](DEBUG): Running supervisor.
2019-02-21T20:41:03.914Z,1550781663.914 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1031
2019-02-21T20:41:03.916Z,1550781663.916 [controlThread ThreadHandler](INFO): Handler Thread ID is 1032
2019-02-21T20:41:03.916Z,1550781663.916 [controlThread](DEBUG): Initializing ControlThread
2019-02-21T20:41:03.925Z,1550781663.925 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T20:41:03.926Z,1550781663.926 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-21T20:41:03.926Z,1550781663.926 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-21T20:41:03.928Z,1550781663.928 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-21T20:41:03.928Z,1550781663.928 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-21T20:41:03.928Z,1550781663.928 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-21T20:41:03.929Z,1550781663.929 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-21T20:41:03.930Z,1550781663.930 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-21T20:41:03.930Z,1550781663.930 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-21T20:41:03.931Z,1550781663.931 [SBIT](INFO): Initialize SBIT Component.
2019-02-21T20:41:03.931Z,1550781663.931 [SBIT](IMPORTANT): git: 2019-02-11-13-g62924c4
2019-02-21T20:41:03.931Z,1550781663.931 [SBIT](INFO): git hash: 62924c492e86bb9d7cd72440302a80a745358b26
2019-02-21T20:41:03.932Z,1550781663.932 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-21T20:41:03.932Z,1550781663.932 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-21T20:41:03.933Z,1550781663.933 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-21T20:41:03.934Z,1550781663.934 [IBIT](INFO): Initialize IBIT Component.
2019-02-21T20:41:03.934Z,1550781663.934 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-21T20:41:03.935Z,1550781663.935 [logger ThreadHandler](INFO): Handler Thread ID is 1033
2019-02-21T20:41:03.949Z,1550781663.949 [CBIT](DEBUG): Initialized mux pins.
2019-02-21T20:41:03.949Z,1550781663.949 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-21T20:41:03.953Z,1550781663.953 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1034
2019-02-21T20:41:03.954Z,1550781663.954 [Radio_Surface](INFO): Powering up
2019-02-21T20:41:03.961Z,1550781663.961 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1035
2019-02-21T20:41:03.962Z,1550781663.962 [CTD_NeilBrown](INFO): Powering down
2019-02-21T20:41:03.973Z,1550781663.973 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-21T20:41:03.973Z,1550781663.973 [CBIT](DEBUG): Initializing heartbeat.
2019-02-21T20:41:03.993Z,1550781663.993 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1036
2019-02-21T20:41:03.994Z,1550781663.994 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T20:41:04.029Z,1550781664.029 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1037
2019-02-21T20:41:04.032Z,1550781664.032 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2019-02-21T20:41:04.041Z,1550781664.041 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-21T20:41:04.043Z,1550781664.043 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2019-02-21T20:41:04.043Z,1550781664.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-21T20:41:04.044Z,1550781664.044 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2019-02-21T20:41:04.044Z,1550781664.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-21T20:41:04.045Z,1550781664.045 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-21T20:41:04.045Z,1550781664.045 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-21T20:41:04.046Z,1550781664.046 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2019-02-21T20:41:04.047Z,1550781664.047 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-21T20:41:04.048Z,1550781664.048 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2019-02-21T20:41:04.048Z,1550781664.048 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-21T20:41:04.050Z,1550781664.050 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2019-02-21T20:41:04.050Z,1550781664.050 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-21T20:41:04.051Z,1550781664.051 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2019-02-21T20:41:04.052Z,1550781664.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-21T20:41:04.065Z,1550781664.065 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2019-02-21T20:41:04.065Z,1550781664.065 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:41:04.067Z,1550781664.067 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2019-02-21T20:41:04.081Z,1550781664.081 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-21T20:41:04.083Z,1550781664.083 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-21T20:41:04.083Z,1550781664.083 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-21T20:41:04.084Z,1550781664.084 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-21T20:41:04.085Z,1550781664.085 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-21T20:41:04.095Z,1550781664.095 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T20:41:04.105Z,1550781664.105 [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>


2019-02-21T20:41:04.106Z,1550781664.106 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-21T20:41:04.141Z,1550781664.141 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:41:04.177Z,1550781664.177 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-21T20:41:04.179Z,1550781664.179 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-21T20:41:04.180Z,1550781664.180 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T20:41:04.215Z,1550781664.215 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-21T20:41:04.218Z,1550781664.218 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-21T20:41:04.223Z,1550781664.223 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-21T20:41:04.236Z,1550781664.236 [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>


2019-02-21T20:41:04.241Z,1550781664.241 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-21T20:41:04.275Z,1550781664.275 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-21T20:41:04.294Z,1550781664.294 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-21T20:41:04.295Z,1550781664.295 [DUSBL_Hydroid](INFO): Powering up
2019-02-21T20:41:04.295Z,1550781664.295 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-21T20:41:04.309Z,1550781664.309 [Micromodem](INFO): Start
2019-02-21T20:41:04.389Z,1550781664.389 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T20:41:04.411Z,1550781664.411 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:41:04.417Z,1550781664.417 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-21T20:41:04.418Z,1550781664.418 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:41:04.425Z,1550781664.425 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-21T20:41:04.426Z,1550781664.426 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:41:04.433Z,1550781664.433 [MassServo](DEBUG): Initializing MassServo.
2019-02-21T20:41:04.434Z,1550781664.434 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:41:04.441Z,1550781664.441 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-21T20:41:04.442Z,1550781664.442 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:41:04.449Z,1550781664.449 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-21T20:41:04.702Z,1550781664.702 [Micromodem](INFO): Starting
2019-02-21T20:41:04.703Z,1550781664.703 [Micromodem](INFO): Powering up
2019-02-21T20:41:04.703Z,1550781664.703 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:41:04.805Z,1550781664.805 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T20:41:04.841Z,1550781664.841 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T20:41:05.139Z,1550781665.139 [Micromodem](INFO): Starting
2019-02-21T20:41:05.462Z,1550781665.462 [Micromodem](INFO): Starting
2019-02-21T20:41:05.866Z,1550781665.866 [Micromodem](INFO): Starting
2019-02-21T20:41:06.270Z,1550781666.270 [Micromodem](INFO): Starting
2019-02-21T20:41:06.674Z,1550781666.674 [Micromodem](INFO): Starting
2019-02-21T20:41:07.078Z,1550781667.078 [Micromodem](INFO): Starting
2019-02-21T20:41:07.490Z,1550781667.490 [Micromodem](INFO): Starting
2019-02-21T20:41:07.629Z,1550781667.629 [Aanderaa_O2](INFO): Powering down
2019-02-21T20:41:07.886Z,1550781667.886 [Micromodem](INFO): Starting
2019-02-21T20:41:08.290Z,1550781668.290 [Micromodem](INFO): Starting
2019-02-21T20:41:08.695Z,1550781668.695 [Micromodem](INFO): Starting
2019-02-21T20:41:09.098Z,1550781669.098 [Micromodem](INFO): Starting
2019-02-21T20:41:09.502Z,1550781669.502 [Micromodem](INFO): Starting
2019-02-21T20:41:09.502Z,1550781669.502 [Micromodem](INFO): Starting
2019-02-21T20:41:09.503Z,1550781669.503 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:09.503Z,1550781669.503 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T20:41:09.503Z,1550781669.503 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T20:41:09.504Z,1550781669.504 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:09.504Z,1550781669.504 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T20:41:09.504Z,1550781669.504 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:09.504Z,1550781669.504 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T20:41:09.504Z,1550781669.504 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:09.506Z,1550781669.506 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T20:41:09.507Z,1550781669.507 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T20:41:09.508Z,1550781669.508 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T20:41:09.906Z,1550781669.906 [Micromodem](INFO): Starting
2019-02-21T20:41:10.310Z,1550781670.310 [Micromodem](INFO): Starting
2019-02-21T20:41:10.718Z,1550781670.718 [Micromodem](INFO): Starting
2019-02-21T20:41:10.719Z,1550781670.719 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T20:41:10.719Z,1550781670.719 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T20:41:11Z,2*8A

2019-02-21T20:41:11.130Z,1550781671.130 [Micromodem](INFO): Starting
2019-02-21T20:41:11.526Z,1550781671.526 [Micromodem](INFO): Starting
2019-02-21T20:41:11.930Z,1550781671.930 [Micromodem](INFO): Starting
2019-02-21T20:41:12.334Z,1550781672.334 [Micromodem](INFO): Starting
2019-02-21T20:41:12.738Z,1550781672.738 [Micromodem](INFO): Starting
2019-02-21T20:41:13.142Z,1550781673.142 [Micromodem](INFO): Starting
2019-02-21T20:41:13.550Z,1550781673.550 [Micromodem](INFO): Starting
2019-02-21T20:41:13.954Z,1550781673.954 [Micromodem](INFO): Starting
2019-02-21T20:41:14.358Z,1550781674.358 [Micromodem](INFO): Starting
2019-02-21T20:41:14.759Z,1550781674.759 [Micromodem](INFO): Starting
2019-02-21T20:41:15.166Z,1550781675.166 [Micromodem](INFO): Starting
2019-02-21T20:41:15.570Z,1550781675.570 [Micromodem](INFO): Starting
2019-02-21T20:41:15.974Z,1550781675.974 [Micromodem](INFO): Starting
2019-02-21T20:41:16.378Z,1550781676.378 [Micromodem](INFO): Starting
2019-02-21T20:41:16.782Z,1550781676.782 [Micromodem](INFO): Starting
2019-02-21T20:41:17.207Z,1550781677.207 [Micromodem](INFO): Starting
2019-02-21T20:41:17.590Z,1550781677.590 [Micromodem](INFO): Starting
2019-02-21T20:41:17.627Z,1550781677.627 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-21T20:41:18.002Z,1550781678.002 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:41:18.010Z,1550781678.010 [Micromodem](INFO): Starting
2019-02-21T20:41:18.398Z,1550781678.398 [Micromodem](INFO): Starting
2019-02-21T20:41:18.802Z,1550781678.802 [Micromodem](INFO): Starting
2019-02-21T20:41:19.206Z,1550781679.206 [Micromodem](INFO): Starting
2019-02-21T20:41:19.619Z,1550781679.619 [Micromodem](INFO): Starting
2019-02-21T20:41:20.015Z,1550781680.015 [Micromodem](INFO): Starting
2019-02-21T20:41:20.419Z,1550781680.419 [Micromodem](INFO): Starting
2019-02-21T20:41:20.822Z,1550781680.822 [Micromodem](INFO): Starting
2019-02-21T20:41:21.226Z,1550781681.226 [Micromodem](INFO): Starting
2019-02-21T20:41:21.630Z,1550781681.630 [Micromodem](INFO): Starting
2019-02-21T20:41:22.034Z,1550781682.034 [Micromodem](INFO): Starting
2019-02-21T20:41:22.438Z,1550781682.438 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-02-21T20:41:22.449Z,1550781682.449 [Micromodem](INFO): Starting
2019-02-21T20:41:22.842Z,1550781682.842 [Micromodem](INFO): Starting
2019-02-21T20:41:23.246Z,1550781683.246 [Micromodem](INFO): Starting
2019-02-21T20:41:23.650Z,1550781683.650 [Micromodem](INFO): Starting
2019-02-21T20:41:24.054Z,1550781684.054 [Micromodem](INFO): Starting
2019-02-21T20:41:24.458Z,1550781684.458 [Micromodem](INFO): Starting
2019-02-21T20:41:24.458Z,1550781684.458 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2019-02-21T20:41:24.459Z,1550781684.459 [Micromodem] Communications Fault, FailCount= 1
2019-02-21T20:41:24.459Z,1550781684.459 [Micromodem](ERROR): Communications Fault
2019-02-21T20:41:24.510Z,1550781684.510 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T20:41:24.862Z,1550781684.862 [Micromodem](INFO): Stop
2019-02-21T20:41:24.862Z,1550781684.862 [Micromodem](INFO): uninitialize
2019-02-21T20:41:24.862Z,1550781684.862 [Micromodem](INFO): Powering down
2019-02-21T20:41:25.266Z,1550781685.266 [Micromodem](INFO): Stopping
2019-02-21T20:41:25.670Z,1550781685.670 [Micromodem](INFO): Stopped
2019-02-21T20:41:25.718Z,1550781685.718 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T20:41:25.718Z,1550781685.718 [Micromodem] No Fault, FailCount= 1
2019-02-21T20:41:26.074Z,1550781686.074 [Micromodem](INFO): Start
2019-02-21T20:41:26.478Z,1550781686.478 [Micromodem](INFO): Start
2019-02-21T20:41:26.902Z,1550781686.902 [Micromodem](INFO): Start
2019-02-21T20:41:27.294Z,1550781687.294 [Micromodem](INFO): Start
2019-02-21T20:41:27.690Z,1550781687.690 [Micromodem](INFO): Start
2019-02-21T20:41:28.094Z,1550781688.094 [Micromodem](INFO): Start
2019-02-21T20:41:28.498Z,1550781688.498 [Micromodem](INFO): Starting
2019-02-21T20:41:28.498Z,1550781688.498 [Micromodem](INFO): Powering up
2019-02-21T20:41:28.498Z,1550781688.498 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:41:28.903Z,1550781688.903 [Micromodem](INFO): Starting
2019-02-21T20:41:29.306Z,1550781689.306 [Micromodem](INFO): Starting
2019-02-21T20:41:29.710Z,1550781689.710 [Micromodem](INFO): Starting
2019-02-21T20:41:29.711Z,1550781689.711 [NAL9602](INFO): Powering up NAL9602
2019-02-21T20:41:30.114Z,1550781690.114 [Micromodem](INFO): Starting
2019-02-21T20:41:30.526Z,1550781690.526 [Micromodem](INFO): Starting
2019-02-21T20:41:30.922Z,1550781690.922 [Micromodem](INFO): Starting
2019-02-21T20:41:31.326Z,1550781691.326 [Micromodem](INFO): Starting
2019-02-21T20:41:31.730Z,1550781691.730 [Micromodem](INFO): Starting
2019-02-21T20:41:32.134Z,1550781692.134 [Micromodem](INFO): Starting
2019-02-21T20:41:32.550Z,1550781692.550 [Micromodem](INFO): Starting
2019-02-21T20:41:32.579Z,1550781692.579 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-21T20:41:32.584Z,1550781692.584 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-21T20:41:32.954Z,1550781692.954 [Micromodem](INFO): Starting
2019-02-21T20:41:33.358Z,1550781693.358 [Micromodem](INFO): Starting
2019-02-21T20:41:33.358Z,1550781693.358 [Micromodem](INFO): Starting
2019-02-21T20:41:33.358Z,1550781693.358 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:33.359Z,1550781693.359 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T20:41:33.359Z,1550781693.359 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T20:41:33.359Z,1550781693.359 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:33.359Z,1550781693.359 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T20:41:33.359Z,1550781693.359 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:33.360Z,1550781693.360 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T20:41:33.360Z,1550781693.360 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:33.360Z,1550781693.360 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T20:41:33.360Z,1550781693.360 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T20:41:33.360Z,1550781693.360 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T20:41:33.762Z,1550781693.762 [Micromodem](INFO): Starting
2019-02-21T20:41:34.166Z,1550781694.166 [Micromodem](INFO): Starting
2019-02-21T20:41:34.570Z,1550781694.570 [Micromodem](INFO): Starting
2019-02-21T20:41:34.571Z,1550781694.571 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T20:41:34.571Z,1550781694.571 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T20:41:35Z,2*8C

2019-02-21T20:41:35.074Z,1550781695.074 [Micromodem](INFO): Starting
2019-02-21T20:41:35.090Z,1550781695.090 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-02-21T20:41:35.090Z,1550781695.090 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-02-21T20:41:35.090Z,1550781695.090 [RDI_Pathfinder](ERROR): Communications Fault
2019-02-21T20:41:35.214Z,1550781695.214 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-02-21T20:41:35.474Z,1550781695.474 [Micromodem](INFO): Starting
2019-02-21T20:41:35.561Z,1550781695.561 [RDI_Pathfinder](INFO): Powering down
2019-02-21T20:41:35.878Z,1550781695.878 [Micromodem](INFO): Starting
2019-02-21T20:41:36.282Z,1550781696.282 [Micromodem](INFO): Starting
2019-02-21T20:41:36.502Z,1550781696.502 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-02-21T20:41:36.502Z,1550781696.502 [RDI_Pathfinder] No Fault, FailCount= 1
2019-02-21T20:41:36.686Z,1550781696.686 [Micromodem](INFO): Starting
2019-02-21T20:41:37.090Z,1550781697.090 [Micromodem](INFO): Starting
2019-02-21T20:41:37.494Z,1550781697.494 [Micromodem](INFO): Starting
2019-02-21T20:41:37.898Z,1550781697.898 [Micromodem](INFO): Starting
2019-02-21T20:41:38.017Z,1550781698.017 [NavChartDb](INFO): # of records loaded: 5000
2019-02-21T20:41:38.302Z,1550781698.302 [Micromodem](INFO): Starting
2019-02-21T20:41:38.706Z,1550781698.706 [Micromodem](INFO): Starting
2019-02-21T20:41:39.110Z,1550781699.110 [Micromodem](INFO): Starting
2019-02-21T20:41:39.514Z,1550781699.514 [Micromodem](INFO): Starting
2019-02-21T20:41:39.916Z,1550781699.916 [Micromodem](INFO): Starting
2019-02-21T20:41:40.322Z,1550781700.322 [Micromodem](INFO): Starting
2019-02-21T20:41:40.726Z,1550781700.726 [Micromodem](INFO): Starting
2019-02-21T20:41:40.727Z,1550781700.727 [NAL9602](INFO): NAL9602 initialized
2019-02-21T20:41:41.130Z,1550781701.130 [Micromodem](INFO): Starting
2019-02-21T20:41:41.534Z,1550781701.534 [Micromodem](INFO): Starting
2019-02-21T20:41:41.549Z,1550781701.549 [NAL9602](DEBUG): Fix Requested
2019-02-21T20:41:41.938Z,1550781701.938 [Micromodem](INFO): Starting
2019-02-21T20:41:42.342Z,1550781702.342 [Micromodem](INFO): Starting
2019-02-21T20:41:42.746Z,1550781702.746 [Micromodem](INFO): Starting
2019-02-21T20:41:43.150Z,1550781703.150 [Micromodem](INFO): Starting
2019-02-21T20:41:43.558Z,1550781703.558 [Micromodem](INFO): Starting
2019-02-21T20:41:43.634Z,1550781703.634 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.023238
CHAN A1 (24V): -0.190577
CHAN A2 (12V): -0.007454
CHAN A3 (5V): -0.002096
CHAN B0 (3.3V): 0.000240
CHAN B1 (3.15aV): 0.000231
CHAN B2 (3.15bV): -0.000208
CHAN B3 (GND): 0.002084
OPEN: 0.005239
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-21T20:41:43.958Z,1550781703.958 [Micromodem](INFO): Starting
2019-02-21T20:41:44.362Z,1550781704.362 [Micromodem](INFO): Starting
2019-02-21T20:41:44.766Z,1550781704.766 [Micromodem](INFO): Starting
2019-02-21T20:41:45.170Z,1550781705.170 [Micromodem](INFO): Starting
2019-02-21T20:41:45.574Z,1550781705.574 [Micromodem](INFO): Starting
2019-02-21T20:41:45.982Z,1550781705.982 [Micromodem](INFO): Starting
2019-02-21T20:41:46.382Z,1550781706.382 [Micromodem](INFO): Starting
2019-02-21T20:41:46.786Z,1550781706.786 [Micromodem](INFO): Starting
2019-02-21T20:41:47.198Z,1550781707.198 [Micromodem](INFO): Starting
2019-02-21T20:41:47.594Z,1550781707.594 [Micromodem](INFO): Starting
2019-02-21T20:41:48.180Z,1550781708.180 [Micromodem](INFO): Starting
2019-02-21T20:41:48.181Z,1550781708.181 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2019-02-21T20:41:48.181Z,1550781708.181 [Micromodem] Communications Fault, FailCount= 2
2019-02-21T20:41:48.181Z,1550781708.181 [Micromodem](ERROR): Communications Fault
2019-02-21T20:41:48.302Z,1550781708.302 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T20:41:48.578Z,1550781708.578 [Micromodem](INFO): Stop
2019-02-21T20:41:48.578Z,1550781708.578 [Micromodem](INFO): uninitialize
2019-02-21T20:41:48.578Z,1550781708.578 [Micromodem](INFO): Powering down
2019-02-21T20:41:48.994Z,1550781708.994 [Micromodem](INFO): Stopping
2019-02-21T20:41:49.387Z,1550781709.387 [Micromodem](INFO): Stopped
2019-02-21T20:41:49.514Z,1550781709.514 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T20:41:49.514Z,1550781709.514 [Micromodem] No Fault, FailCount= 2
2019-02-21T20:41:49.798Z,1550781709.798 [Micromodem](INFO): Start
2019-02-21T20:41:50.194Z,1550781710.194 [Micromodem](INFO): Start
2019-02-21T20:41:50.598Z,1550781710.598 [Micromodem](INFO): Start
2019-02-21T20:41:51.002Z,1550781711.002 [Micromodem](INFO): Start
2019-02-21T20:41:51.406Z,1550781711.406 [Micromodem](INFO): Start
2019-02-21T20:41:51.810Z,1550781711.810 [Micromodem](INFO): Start
2019-02-21T20:41:52.214Z,1550781712.214 [Micromodem](INFO): Starting
2019-02-21T20:41:52.214Z,1550781712.214 [Micromodem](INFO): Powering up
2019-02-21T20:41:52.214Z,1550781712.214 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:41:52.618Z,1550781712.618 [Micromodem](INFO): Starting
2019-02-21T20:41:53.030Z,1550781713.030 [Micromodem](INFO): Starting
2019-02-21T20:41:53.426Z,1550781713.426 [Micromodem](INFO): Starting
2019-02-21T20:41:53.830Z,1550781713.830 [Micromodem](INFO): Starting
2019-02-21T20:41:54.234Z,1550781714.234 [Micromodem](INFO): Starting
2019-02-21T20:41:54.638Z,1550781714.638 [Micromodem](INFO): Starting
2019-02-21T20:41:55.042Z,1550781715.042 [Micromodem](INFO): Starting
2019-02-21T20:41:55.446Z,1550781715.446 [Micromodem](INFO): Starting
2019-02-21T20:41:55.866Z,1550781715.866 [Micromodem](INFO): Starting
2019-02-21T20:41:56.266Z,1550781716.266 [Micromodem](INFO): Starting
2019-02-21T20:41:56.658Z,1550781716.658 [Micromodem](INFO): Starting
2019-02-21T20:41:57.070Z,1550781717.070 [Micromodem](INFO): Starting
2019-02-21T20:41:57.070Z,1550781717.070 [Micromodem](INFO): Starting
2019-02-21T20:41:57.071Z,1550781717.071 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:57.071Z,1550781717.071 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T20:41:57.071Z,1550781717.071 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T20:41:57.072Z,1550781717.072 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:57.073Z,1550781717.073 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T20:41:57.074Z,1550781717.074 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:57.075Z,1550781717.075 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T20:41:57.076Z,1550781717.076 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:41:57.077Z,1550781717.077 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T20:41:57.078Z,1550781717.078 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T20:41:57.079Z,1550781717.079 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T20:41:57.466Z,1550781717.466 [Micromodem](INFO): Starting
2019-02-21T20:41:57.874Z,1550781717.874 [Micromodem](INFO): Starting
2019-02-21T20:41:58.274Z,1550781718.274 [Micromodem](INFO): Starting
2019-02-21T20:41:58.275Z,1550781718.275 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T20:41:58.275Z,1550781718.275 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T20:41:59Z,2*86

2019-02-21T20:41:58.678Z,1550781718.678 [Micromodem](INFO): Starting
2019-02-21T20:41:59.082Z,1550781719.082 [Micromodem](INFO): Starting
2019-02-21T20:41:59.112Z,1550781719.112 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-02-21T20:41:59.112Z,1550781719.112 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-02-21T20:41:59.112Z,1550781719.112 [RDI_Pathfinder](ERROR): Communications Fault
2019-02-21T20:41:59.190Z,1550781719.190 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-02-21T20:41:59.514Z,1550781719.514 [Micromodem](INFO): Starting
2019-02-21T20:41:59.617Z,1550781719.617 [RDI_Pathfinder](INFO): Powering down
2019-02-21T20:41:59.890Z,1550781719.890 [Micromodem](INFO): Starting
2019-02-21T20:42:00.294Z,1550781720.294 [Micromodem](INFO): Starting
2019-02-21T20:42:00.382Z,1550781720.382 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-02-21T20:42:00.382Z,1550781720.382 [RDI_Pathfinder] No Fault, FailCount= 2
2019-02-21T20:42:00.706Z,1550781720.706 [Micromodem](INFO): Starting
2019-02-21T20:42:01.102Z,1550781721.102 [Micromodem](INFO): Starting
2019-02-21T20:42:01.506Z,1550781721.506 [Micromodem](INFO): Starting
2019-02-21T20:42:01.942Z,1550781721.942 [Micromodem](INFO): Starting
2019-02-21T20:42:02.314Z,1550781722.314 [Micromodem](INFO): Starting
2019-02-21T20:42:02.718Z,1550781722.718 [Micromodem](INFO): Starting
2019-02-21T20:42:03.130Z,1550781723.130 [Micromodem](INFO): Starting
2019-02-21T20:42:03.526Z,1550781723.526 [Micromodem](INFO): Starting
2019-02-21T20:42:03.930Z,1550781723.930 [Micromodem](INFO): Starting
2019-02-21T20:42:04.342Z,1550781724.342 [Micromodem](INFO): Starting
2019-02-21T20:42:04.738Z,1550781724.738 [Micromodem](INFO): Starting
2019-02-21T20:42:05.142Z,1550781725.142 [Micromodem](INFO): Starting
2019-02-21T20:42:05.546Z,1550781725.546 [Micromodem](INFO): Starting
2019-02-21T20:42:05.950Z,1550781725.950 [Micromodem](INFO): Starting
2019-02-21T20:42:06.354Z,1550781726.354 [Micromodem](INFO): Starting
2019-02-21T20:42:06.762Z,1550781726.762 [Micromodem](INFO): Starting
2019-02-21T20:42:07.162Z,1550781727.162 [Micromodem](INFO): Starting
2019-02-21T20:42:07.566Z,1550781727.566 [Micromodem](INFO): Starting
2019-02-21T20:42:07.970Z,1550781727.970 [Micromodem](INFO): Starting
2019-02-21T20:42:08.374Z,1550781728.374 [Micromodem](INFO): Starting
2019-02-21T20:42:08.765Z,1550781728.765 [NavChartDb](INFO): # of records loaded: 10000
2019-02-21T20:42:08.778Z,1550781728.778 [Micromodem](INFO): Starting
2019-02-21T20:42:09.182Z,1550781729.182 [Micromodem](INFO): Starting
2019-02-21T20:42:09.586Z,1550781729.586 [Micromodem](INFO): Starting
2019-02-21T20:42:09.990Z,1550781729.990 [Micromodem](INFO): Starting
2019-02-21T20:42:10.394Z,1550781730.394 [Micromodem](INFO): Starting
2019-02-21T20:42:10.798Z,1550781730.798 [Micromodem](INFO): Starting
2019-02-21T20:42:11.202Z,1550781731.202 [Micromodem](INFO): Starting
2019-02-21T20:42:11.606Z,1550781731.606 [Micromodem](INFO): Starting
2019-02-21T20:42:12.010Z,1550781732.010 [Micromodem](INFO): Starting
2019-02-21T20:42:12.011Z,1550781732.011 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2019-02-21T20:42:12.011Z,1550781732.011 [Micromodem] Communications Fault, FailCount= 3
2019-02-21T20:42:12.011Z,1550781732.011 [Micromodem](ERROR): Communications Fault
2019-02-21T20:42:12.068Z,1550781732.068 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T20:42:12.414Z,1550781732.414 [Micromodem](INFO): Stop
2019-02-21T20:42:12.414Z,1550781732.414 [Micromodem](INFO): uninitialize
2019-02-21T20:42:12.414Z,1550781732.414 [Micromodem](INFO): Powering down
2019-02-21T20:42:12.830Z,1550781732.830 [Micromodem](INFO): Stopping
2019-02-21T20:42:13.222Z,1550781733.222 [Micromodem](INFO): Stopped
2019-02-21T20:42:13.309Z,1550781733.309 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T20:42:13.310Z,1550781733.310 [Micromodem] No Fault, FailCount= 3
2019-02-21T20:42:13.626Z,1550781733.626 [Micromodem](INFO): Start
2019-02-21T20:42:14.030Z,1550781734.030 [Micromodem](INFO): Start
2019-02-21T20:42:14.446Z,1550781734.446 [Micromodem](INFO): Start
2019-02-21T20:42:14.650Z,1550781734.650 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:42:14.942Z,1550781734.942 [Micromodem](INFO): Start
2019-02-21T20:42:15.342Z,1550781735.342 [Micromodem](INFO): Start
2019-02-21T20:42:15.746Z,1550781735.746 [Micromodem](INFO): Start
2019-02-21T20:42:16.150Z,1550781736.150 [Micromodem](INFO): Starting
2019-02-21T20:42:16.150Z,1550781736.150 [Micromodem](INFO): Powering up
2019-02-21T20:42:16.151Z,1550781736.151 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:42:16.579Z,1550781736.579 [Micromodem](INFO): Starting
2019-02-21T20:42:16.966Z,1550781736.966 [Micromodem](INFO): Starting
2019-02-21T20:42:17.362Z,1550781737.362 [Micromodem](INFO): Starting
2019-02-21T20:42:17.774Z,1550781737.774 [Micromodem](INFO): Starting
2019-02-21T20:42:18.058Z,1550781738.058 [NavChartDb](INFO): # of records loaded: 15000
2019-02-21T20:42:18.170Z,1550781738.170 [Micromodem](INFO): Starting
2019-02-21T20:42:18.574Z,1550781738.574 [Micromodem](INFO): Starting
2019-02-21T20:42:18.978Z,1550781738.978 [Micromodem](INFO): Starting
2019-02-21T20:42:19.386Z,1550781739.386 [Micromodem](INFO): Starting
2019-02-21T20:42:19.573Z,1550781739.573 [CommandLine](IMPORTANT): got command quit
2019-02-21T20:42:19.794Z,1550781739.794 [Micromodem](INFO): Starting
2019-02-21T20:42:20.190Z,1550781740.190 [Micromodem](INFO): Starting
2019-02-21T20:42:20.550Z,1550781740.550 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:42:20.577Z,1550781740.577 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-02-21T20:42:20.577Z,1550781740.577 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:42:20.577Z,1550781740.577 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:20.594Z,1550781740.594 [Micromodem](INFO): Starting
2019-02-21T20:42:20.629Z,1550781740.629 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-21T20:42:20.629Z,1550781740.629 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:20.649Z,1550781740.649 [CommandLine](INFO): Join timeout helper Thread ID is 1061
2019-02-21T20:42:20.661Z,1550781740.661 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-21T20:42:20.661Z,1550781740.661 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:20.662Z,1550781740.662 [NavChartDb](INFO): Join timeout helper Thread ID is 1062
2019-02-21T20:42:20.998Z,1550781740.998 [Micromodem](INFO): Starting
2019-02-21T20:42:20.998Z,1550781740.998 [Micromodem](INFO): Starting
2019-02-21T20:42:20.999Z,1550781740.999 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:42:20.999Z,1550781740.999 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T20:42:20.999Z,1550781740.999 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T20:42:20.999Z,1550781740.999 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:42:20.999Z,1550781740.999 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T20:42:20.000Z,1550781741.000 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:42:20.000Z,1550781741.000 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T20:42:21.000Z,1550781741.000 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:42:21.000Z,1550781741.000 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T20:42:21.001Z,1550781741.001 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T20:42:21.002Z,1550781741.002 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T20:42:21.402Z,1550781741.402 [Micromodem](INFO): Starting
2019-02-21T20:42:21.508Z,1550781741.508 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:42:21.509Z,1550781741.509 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.529Z,1550781741.529 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-21T20:42:21.529Z,1550781741.529 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.529Z,1550781741.529 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1063
2019-02-21T20:42:21.613Z,1550781741.613 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:42:21.613Z,1550781741.613 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T20:42:21.614Z,1550781741.614 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.629Z,1550781741.629 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-21T20:42:21.629Z,1550781741.629 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.629Z,1550781741.629 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1064
2019-02-21T20:42:21.741Z,1550781741.741 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:42:21.741Z,1550781741.741 [CTD_NeilBrown](INFO): Powering down
2019-02-21T20:42:21.753Z,1550781741.753 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.769Z,1550781741.769 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-21T20:42:21.769Z,1550781741.769 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.769Z,1550781741.769 [Radio_Surface](INFO): Join timeout helper Thread ID is 1065
2019-02-21T20:42:21.806Z,1550781741.806 [Micromodem](INFO): Starting
2019-02-21T20:42:21.957Z,1550781741.957 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:42:21.957Z,1550781741.957 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.970Z,1550781741.970 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-21T20:42:21.970Z,1550781741.970 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.970Z,1550781741.970 [logger](INFO): Join timeout helper Thread ID is 1066
2019-02-21T20:42:21.981Z,1550781741.981 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:42:21.981Z,1550781741.981 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.989Z,1550781741.989 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-21T20:42:21.990Z,1550781741.990 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.990Z,1550781741.990 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-21T20:42:21.990Z,1550781741.990 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:21.990Z,1550781741.990 [controlThread](INFO): Join timeout helper Thread ID is 1067
2019-02-21T20:42:22.201Z,1550781742.201 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:42:22.201Z,1550781742.201 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-21T20:42:22.202Z,1550781742.202 [DUSBL_Hydroid](INFO): Powering down
2019-02-21T20:42:22.293Z,1550781742.293 [Micromodem](INFO): uninitialize
2019-02-21T20:42:22.293Z,1550781742.293 [Micromodem](INFO): Powering down
2019-02-21T20:42:22.389Z,1550781742.389 [NAL9602](INFO): Powering down
2019-02-21T20:42:22.461Z,1550781742.461 [RDI_Pathfinder](INFO): Powering down
2019-02-21T20:42:22.462Z,1550781742.462 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T20:42:22.463Z,1550781742.463 [Aanderaa_O2](INFO): Powering down
2019-02-21T20:42:22.464Z,1550781742.464 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-21T20:42:22.465Z,1550781742.465 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-21T20:42:22.466Z,1550781742.466 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-21T20:42:22.467Z,1550781742.467 [MissionManager](INFO): Uninitializing Mission Default
2019-02-21T20:42:22.469Z,1550781742.469 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-21T20:42:22.470Z,1550781742.470 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-21T20:42:22.471Z,1550781742.471 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-21T20:42:22.471Z,1550781742.471 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-21T20:42:22.471Z,1550781742.471 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-21T20:42:22.471Z,1550781742.471 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-21T20:42:22.472Z,1550781742.472 [BuoyancyServo](INFO): Powering down
2019-02-21T20:42:22.485Z,1550781742.485 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-21T20:42:22.485Z,1550781742.485 [ElevatorServo](INFO): Powering down
2019-02-21T20:42:22.486Z,1550781742.486 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-21T20:42:22.486Z,1550781742.486 [MassServo](INFO): Powering down
2019-02-21T20:42:22.487Z,1550781742.487 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-21T20:42:22.487Z,1550781742.487 [RudderServo](INFO): Powering down
2019-02-21T20:42:22.488Z,1550781742.488 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-21T20:42:22.488Z,1550781742.488 [ThrusterServo](INFO): Powering down
2019-02-21T20:42:22.489Z,1550781742.489 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-21T20:42:22.489Z,1550781742.489 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-21T20:42:22.489Z,1550781742.489 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-21T20:42:22.489Z,1550781742.489 [CBIT](DEBUG): Powering off loads.
2019-02-21T20:42:22.501Z,1550781742.501 [CBIT](DEBUG): Disabling WDT.
2019-02-21T20:42:22.513Z,1550781742.513 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-21T20:42:22.513Z,1550781742.513 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:22.565Z,1550781742.565 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:22.611Z,1550781742.611 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:22.613Z,1550781742.613 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:22.667Z,1550781742.667 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:42:22.709Z,1550781742.709 [logger ThreadHandler](INFO): Thread cancelled.