2019-02-21T20:19:57.923Z,1550780397.923 [Supervisor](DEBUG): Initializing supervisor.
2019-02-21T20:19:57.926Z,1550780397.926 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T20:19:57.926Z,1550780397.926 [SyncHandler](INFO): Protected caller Thread ID is 2982
2019-02-21T20:19:57.927Z,1550780397.927 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-21T20:19:57.928Z,1550780397.928 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-21T20:19:57.928Z,1550780397.928 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2983
2019-02-21T20:19:57.931Z,1550780397.931 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-21T20:19:58.002Z,1550780398.002 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-21T20:19:58.003Z,1550780398.003 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-21T20:19:58.003Z,1550780398.003 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2984
2019-02-21T20:19:58.004Z,1550780398.004 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-21T20:19:58.005Z,1550780398.005 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-21T20:19:58.005Z,1550780398.005 [logger ThreadHandler](INFO): Protected caller Thread ID is 2985
2019-02-21T20:19:58.007Z,1550780398.007 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-21T20:19:58.008Z,1550780398.008 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-21T20:19:58.009Z,1550780398.009 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-21T20:19:58.425Z,1550780398.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-21T20:19:58.426Z,1550780398.426 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-21T20:19:58.525Z,1550780398.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-21T20:19:58.526Z,1550780398.526 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-21T20:19:58.852Z,1550780398.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-21T20:19:58.853Z,1550780398.853 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-21T20:19:58.994Z,1550780398.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-21T20:19:58.994Z,1550780398.994 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-21T20:19:59.187Z,1550780399.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-21T20:19:59.188Z,1550780399.188 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-21T20:19:59.651Z,1550780399.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-21T20:19:59.651Z,1550780399.651 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-21T20:19:59.859Z,1550780399.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-21T20:19:59.860Z,1550780399.860 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-21T20:20:00.007Z,1550780400.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-21T20:20:00.008Z,1550780400.008 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-21T20:20:00.205Z,1550780400.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-21T20:20:00.206Z,1550780400.206 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-21T20:20:00.303Z,1550780400.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-21T20:20:00.304Z,1550780400.304 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-21T20:20:00.619Z,1550780400.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-21T20:20:00.620Z,1550780400.620 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-21T20:20:00.702Z,1550780400.702 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-21T20:20:00.808Z,1550780400.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-21T20:20:00.809Z,1550780400.809 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-21T20:20:01.303Z,1550780401.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-21T20:20:01.304Z,1550780401.304 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-21T20:20:01.706Z,1550780401.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-21T20:20:01.708Z,1550780401.708 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-21T20:20:01.709Z,1550780401.709 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-21T20:20:01.920Z,1550780401.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-21T20:20:02.022Z,1550780402.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-21T20:20:02.123Z,1550780402.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-21T20:20:02.351Z,1550780402.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-21T20:20:02.351Z,1550780402.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-21T20:20:02.438Z,1550780402.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-21T20:20:02.533Z,1550780402.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-21T20:20:02.633Z,1550780402.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-21T20:20:03.011Z,1550780403.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-21T20:20:03.241Z,1550780403.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-21T20:20:03.436Z,1550780403.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-21T20:20:03.570Z,1550780403.570 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/LOGIN/
2019-02-21T20:20:03.571Z,1550780403.571 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-21T20:20:03.572Z,1550780403.572 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-21T20:20:03.889Z,1550780403.889 [DataOverHttps] Loaded
2019-02-21T20:20:03.889Z,1550780403.889 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-21T20:20:03.903Z,1550780403.903 [Depth_Keller] Loaded
2019-02-21T20:20:03.904Z,1550780403.904 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-21T20:20:03.908Z,1550780403.908 [DropWeight] Loaded
2019-02-21T20:20:03.909Z,1550780403.909 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-21T20:20:03.957Z,1550780403.957 [DUSBL_Hydroid] Loaded
2019-02-21T20:20:03.957Z,1550780403.957 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-21T20:20:03.997Z,1550780403.997 [Micromodem] Loaded
2019-02-21T20:20:03.997Z,1550780403.997 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-02-21T20:20:04.093Z,1550780404.093 [NAL9602] Loaded
2019-02-21T20:20:04.093Z,1550780404.093 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-21T20:20:04.109Z,1550780404.109 [Onboard] Loaded
2019-02-21T20:20:04.109Z,1550780404.109 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-21T20:20:04.119Z,1550780404.119 [Radio_Surface] Loaded
2019-02-21T20:20:04.119Z,1550780404.119 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-21T20:20:04.121Z,1550780404.121 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407534E0
2019-02-21T20:20:04.121Z,1550780404.121 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3064
2019-02-21T20:20:04.149Z,1550780404.149 [RDI_Pathfinder] Loaded
2019-02-21T20:20:04.149Z,1550780404.149 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-21T20:20:04.171Z,1550780404.171 [RDI_PathfinderUp] Loaded
2019-02-21T20:20:04.171Z,1550780404.171 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-21T20:20:05.953Z,1550780405.953 [BPC1] Loaded
2019-02-21T20:20:05.953Z,1550780405.953 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-21T20:20:05.953Z,1550780405.953 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-21T20:20:05.954Z,1550780405.954 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-21T20:20:05.968Z,1550780405.968 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-21T20:20:05.969Z,1550780405.969 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-21T20:20:06.076Z,1550780406.076 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-21T20:20:06.076Z,1550780406.076 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-21T20:20:06.134Z,1550780406.134 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-21T20:20:06.134Z,1550780406.134 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-21T20:20:06.193Z,1550780406.193 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-21T20:20:06.193Z,1550780406.193 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-21T20:20:06.209Z,1550780406.209 [NavChart] Loaded
2019-02-21T20:20:06.209Z,1550780406.209 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-21T20:20:06.213Z,1550780406.213 [UniversalFixResidualReporter] Loaded
2019-02-21T20:20:06.214Z,1550780406.214 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-21T20:20:06.214Z,1550780406.214 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-21T20:20:06.215Z,1550780406.215 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-21T20:20:06.318Z,1550780406.318 [BuoyancyServo] Loaded
2019-02-21T20:20:06.319Z,1550780406.319 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-21T20:20:06.334Z,1550780406.334 [ElevatorServo] Loaded
2019-02-21T20:20:06.334Z,1550780406.334 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-21T20:20:06.349Z,1550780406.349 [MassServo] Loaded
2019-02-21T20:20:06.349Z,1550780406.349 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-21T20:20:06.365Z,1550780406.365 [RudderServo] Loaded
2019-02-21T20:20:06.365Z,1550780406.365 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-21T20:20:06.379Z,1550780406.379 [ThrusterServo] Loaded
2019-02-21T20:20:06.380Z,1550780406.380 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-21T20:20:06.380Z,1550780406.380 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-21T20:20:06.381Z,1550780406.381 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-21T20:20:06.561Z,1550780406.561 [Aanderaa_O2] Loaded
2019-02-21T20:20:06.561Z,1550780406.561 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-21T20:20:06.645Z,1550780406.645 [CTD_NeilBrown] Loaded
2019-02-21T20:20:06.646Z,1550780406.646 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-21T20:20:06.647Z,1550780406.647 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-21T20:20:06.647Z,1550780406.647 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3065
2019-02-21T20:20:06.691Z,1550780406.691 [WetLabsSeaOWL_UV_A] Loaded
2019-02-21T20:20:06.691Z,1550780406.691 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-21T20:20:06.693Z,1550780406.693 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-21T20:20:06.693Z,1550780406.693 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 3066
2019-02-21T20:20:06.693Z,1550780406.693 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-21T20:20:06.694Z,1550780406.694 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-21T20:20:06.935Z,1550780406.935 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-21T20:20:06.935Z,1550780406.935 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-21T20:20:06.978Z,1550780406.978 [DepthRateCalculator] Loaded
2019-02-21T20:20:06.978Z,1550780406.978 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-21T20:20:06.984Z,1550780406.984 [PitchRateCalculator] Loaded
2019-02-21T20:20:06.984Z,1550780406.984 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-21T20:20:06.997Z,1550780406.997 [SpeedCalculator] Loaded
2019-02-21T20:20:06.997Z,1550780406.997 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-21T20:20:07.017Z,1550780407.017 [TempGradientCalculator] Loaded
2019-02-21T20:20:07.018Z,1550780407.018 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-21T20:20:07.023Z,1550780407.023 [YawRateCalculator] Loaded
2019-02-21T20:20:07.024Z,1550780407.024 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-21T20:20:07.063Z,1550780407.063 [ElevatorOffsetCalculator] Loaded
2019-02-21T20:20:07.063Z,1550780407.063 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-21T20:20:07.064Z,1550780407.064 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-21T20:20:07.064Z,1550780407.064 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-21T20:20:07.200Z,1550780407.200 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-21T20:20:07.222Z,1550780407.222 [SBIT] Loaded
2019-02-21T20:20:07.222Z,1550780407.222 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-21T20:20:07.223Z,1550780407.223 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-21T20:20:07.234Z,1550780407.234 [IBIT] Loaded
2019-02-21T20:20:07.235Z,1550780407.235 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-21T20:20:07.238Z,1550780407.238 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-21T20:20:07.377Z,1550780407.377 [CBIT] Loaded
2019-02-21T20:20:07.377Z,1550780407.377 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-21T20:20:07.377Z,1550780407.377 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-21T20:20:07.378Z,1550780407.378 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-21T20:20:07.445Z,1550780407.445 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-21T20:20:07.446Z,1550780407.446 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-21T20:20:07.545Z,1550780407.545 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-21T20:20:07.545Z,1550780407.545 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-21T20:20:07.615Z,1550780407.615 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-21T20:20:07.698Z,1550780407.698 [VerticalControl] Loaded
2019-02-21T20:20:07.698Z,1550780407.698 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-21T20:20:07.699Z,1550780407.699 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-21T20:20:07.756Z,1550780407.756 [HorizontalControl] Loaded
2019-02-21T20:20:07.757Z,1550780407.757 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-21T20:20:07.757Z,1550780407.757 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-21T20:20:07.759Z,1550780407.759 [SpeedControl] Loaded
2019-02-21T20:20:07.759Z,1550780407.759 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-21T20:20:07.760Z,1550780407.760 [LoopControl](DEBUG): Construct LoopControl.
2019-02-21T20:20:07.761Z,1550780407.761 [LoopControl] Loaded
2019-02-21T20:20:07.761Z,1550780407.761 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-21T20:20:07.761Z,1550780407.761 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-21T20:20:07.762Z,1550780407.762 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-21T20:20:07.790Z,1550780407.790 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-21T20:20:07.794Z,1550780407.794 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-21T20:20:07.795Z,1550780407.795 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-21T20:20:07.802Z,1550780407.802 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-21T20:20:07.803Z,1550780407.803 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0
2019-02-21T20:20:07.803Z,1550780407.803 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3067
2019-02-21T20:20:07.808Z,1550780407.808 [Supervisor](INFO): Main Thread ID is 2981
2019-02-21T20:20:07.808Z,1550780407.808 [Supervisor](DEBUG): Running supervisor.
2019-02-21T20:20:07.809Z,1550780407.809 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3068
2019-02-21T20:20:07.811Z,1550780407.811 [controlThread ThreadHandler](INFO): Handler Thread ID is 3069
2019-02-21T20:20:07.811Z,1550780407.811 [controlThread](DEBUG): Initializing ControlThread
2019-02-21T20:20:07.820Z,1550780407.820 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T20:20:07.821Z,1550780407.821 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-21T20:20:07.821Z,1550780407.821 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-21T20:20:07.823Z,1550780407.823 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-21T20:20:07.823Z,1550780407.823 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-21T20:20:07.823Z,1550780407.823 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-21T20:20:07.824Z,1550780407.824 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-21T20:20:07.824Z,1550780407.824 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-21T20:20:07.824Z,1550780407.824 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-21T20:20:07.825Z,1550780407.825 [SBIT](INFO): Initialize SBIT Component.
2019-02-21T20:20:07.826Z,1550780407.826 [SBIT](IMPORTANT): git: 2019-02-11-13-g62924c4
2019-02-21T20:20:07.826Z,1550780407.826 [SBIT](INFO): git hash: 62924c492e86bb9d7cd72440302a80a745358b26
2019-02-21T20:20:07.826Z,1550780407.826 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-21T20:20:07.826Z,1550780407.826 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-21T20:20:07.827Z,1550780407.827 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-21T20:20:07.828Z,1550780407.828 [IBIT](INFO): Initialize IBIT Component.
2019-02-21T20:20:07.829Z,1550780407.829 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-21T20:20:07.830Z,1550780407.830 [logger ThreadHandler](INFO): Handler Thread ID is 3070
2019-02-21T20:20:07.841Z,1550780407.841 [CBIT](DEBUG): Initialized mux pins.
2019-02-21T20:20:07.841Z,1550780407.841 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-21T20:20:07.849Z,1550780407.849 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3071
2019-02-21T20:20:07.850Z,1550780407.850 [Radio_Surface](INFO): Powering up
2019-02-21T20:20:07.861Z,1550780407.861 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3072
2019-02-21T20:20:07.862Z,1550780407.862 [CTD_NeilBrown](INFO): Powering down
2019-02-21T20:20:07.869Z,1550780407.869 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-21T20:20:07.869Z,1550780407.869 [CBIT](DEBUG): Initializing heartbeat.
2019-02-21T20:20:07.889Z,1550780407.889 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 3073
2019-02-21T20:20:07.890Z,1550780407.890 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T20:20:07.920Z,1550780407.920 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3074
2019-02-21T20:20:07.923Z,1550780407.923 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2019-02-21T20:20:07.934Z,1550780407.934 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-21T20:20:07.935Z,1550780407.935 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2019-02-21T20:20:07.936Z,1550780407.936 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-21T20:20:07.937Z,1550780407.937 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2019-02-21T20:20:07.937Z,1550780407.937 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-21T20:20:07.939Z,1550780407.939 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2019-02-21T20:20:07.939Z,1550780407.939 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-21T20:20:07.940Z,1550780407.940 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2019-02-21T20:20:07.941Z,1550780407.941 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-21T20:20:07.941Z,1550780407.941 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-21T20:20:07.941Z,1550780407.941 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-21T20:20:07.943Z,1550780407.943 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2019-02-21T20:20:07.943Z,1550780407.943 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-21T20:20:07.944Z,1550780407.944 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2019-02-21T20:20:07.945Z,1550780407.945 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-21T20:20:07.946Z,1550780407.946 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2019-02-21T20:20:07.946Z,1550780407.946 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:20:07.948Z,1550780407.948 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2019-02-21T20:20:07.977Z,1550780407.977 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-21T20:20:07.979Z,1550780407.979 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-21T20:20:07.979Z,1550780407.979 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-21T20:20:07.980Z,1550780407.980 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-21T20:20:07.981Z,1550780407.981 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-21T20:20:07.996Z,1550780407.996 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T20:20:08.029Z,1550780408.029 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:20:08.085Z,1550780408.085 [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:20:08.086Z,1550780408.086 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-21T20:20:08.212Z,1550780408.212 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-21T20:20:08.214Z,1550780408.214 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-21T20:20:08.215Z,1550780408.215 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T20:20:08.266Z,1550780408.266 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-21T20:20:08.287Z,1550780408.287 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-21T20:20:08.293Z,1550780408.293 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-21T20:20:08.296Z,1550780408.296 [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:20:08.301Z,1550780408.301 [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:20:08.306Z,1550780408.306 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-21T20:20:08.310Z,1550780408.310 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-21T20:20:08.311Z,1550780408.311 [DUSBL_Hydroid](INFO): Powering up
2019-02-21T20:20:08.311Z,1550780408.311 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-21T20:20:08.325Z,1550780408.325 [Micromodem](INFO): Start
2019-02-21T20:20:08.461Z,1550780408.461 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T20:20:08.577Z,1550780408.577 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:20:08.585Z,1550780408.585 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-21T20:20:08.586Z,1550780408.586 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:20:08.593Z,1550780408.593 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-21T20:20:08.594Z,1550780408.594 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:20:08.600Z,1550780408.600 [MassServo](DEBUG): Initializing MassServo.
2019-02-21T20:20:08.600Z,1550780408.600 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:20:08.609Z,1550780408.609 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-21T20:20:08.610Z,1550780408.610 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:20:08.617Z,1550780408.617 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-21T20:20:08.832Z,1550780408.832 [Micromodem](INFO): Starting
2019-02-21T20:20:08.832Z,1550780408.832 [Micromodem](INFO): Powering up
2019-02-21T20:20:08.832Z,1550780408.832 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:20:08.965Z,1550780408.965 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T20:20:17.307Z,1550780417.307 [Micromodem](INFO): Starting
2019-02-21T20:20:17.308Z,1550780417.308 [Micromodem](INFO): Nmea in: $CAREV,234008,INIT,2.0.27690*40

2019-02-21T20:20:17.328Z,1550780417.328 [Micromodem](CRITICAL): SIGSEGV: address not mapped to object
2019-02-21T20:20:17.328Z,1550780417.328 [Micromodem](CRITICAL): Bad address: 0x8
2019-02-21T20:20:17.328Z,1550780417.328 [Micromodem](CRITICAL): Backtrace: 
bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1780c8]
bin/LRAUV(_ZN9Component7executeEv+0x31c) [0xd60b4]
bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x177c64]

2019-02-21T20:20:17.337Z,1550780417.337 [Micromodem] Software Fault, FailCount= 1
2019-02-21T20:20:17.337Z,1550780417.337 [Micromodem](ERROR): Software Fault
2019-02-21T20:20:17.345Z,1550780417.345 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T20:20:17.345Z,1550780417.345 [SyncHandler](INFO): Protected caller Thread ID is 3085
2019-02-21T20:20:17.679Z,1550780417.679 [CommandLine](FAULT): Scheduling is paused
2019-02-21T20:20:17.679Z,1550780417.679 [CBIT](INFO): Critical error at 20190221T202017
2019-02-21T20:20:17.680Z,1550780417.680 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-21T20:20:17.683Z,1550780417.683 [CBIT](ERROR): Software Fault in component: Micromodem
2019-02-21T20:20:17.711Z,1550780417.711 [Micromodem](INFO): Stop
2019-02-21T20:20:17.711Z,1550780417.711 [Micromodem](INFO): uninitialize
2019-02-21T20:20:17.711Z,1550780417.711 [Micromodem](INFO): Powering down
2019-02-21T20:20:18.098Z,1550780418.098 [Micromodem](INFO): Stopping
2019-02-21T20:20:18.233Z,1550780418.233 [Aanderaa_O2](INFO): Powering down
2019-02-21T20:20:18.502Z,1550780418.502 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004623
2019-02-21T20:20:18.506Z,1550780418.506 [Micromodem](INFO): Stopped
2019-02-21T20:20:18.545Z,1550780418.545 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T20:20:18.545Z,1550780418.545 [Micromodem] No Fault, FailCount= 1
2019-02-21T20:20:18.906Z,1550780418.906 [Micromodem](INFO): Start
2019-02-21T20:20:19.310Z,1550780419.310 [Micromodem](INFO): Start
2019-02-21T20:20:19.727Z,1550780419.727 [Micromodem](INFO): Start
2019-02-21T20:20:20.118Z,1550780420.118 [Micromodem](INFO): Start
2019-02-21T20:20:20.522Z,1550780420.522 [Micromodem](INFO): Start
2019-02-21T20:20:20.938Z,1550780420.938 [Micromodem](INFO): Start
2019-02-21T20:20:21.330Z,1550780421.330 [Micromodem](INFO): Starting
2019-02-21T20:20:21.331Z,1550780421.331 [Micromodem](INFO): Powering up
2019-02-21T20:20:21.331Z,1550780421.331 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:20:21.734Z,1550780421.734 [Micromodem](INFO): Starting
2019-02-21T20:20:22.138Z,1550780422.138 [Micromodem](INFO): Starting
2019-02-21T20:20:22.417Z,1550780422.417 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:20:22.542Z,1550780422.542 [Micromodem](INFO): Starting
2019-02-21T20:20:22.946Z,1550780422.946 [Micromodem](INFO): Starting
2019-02-21T20:20:23.350Z,1550780423.350 [Micromodem](INFO): Starting
2019-02-21T20:20:23.754Z,1550780423.754 [Micromodem](INFO): Starting
2019-02-21T20:20:24.158Z,1550780424.158 [Micromodem](INFO): Starting
2019-02-21T20:20:24.562Z,1550780424.562 [Micromodem](INFO): Starting
2019-02-21T20:20:24.966Z,1550780424.966 [Micromodem](INFO): Starting
2019-02-21T20:20:25.370Z,1550780425.370 [Micromodem](INFO): Starting
2019-02-21T20:20:25.372Z,1550780425.372 [Micromodem](INFO): Nmea in: $CAREV,234020,INIT,2.0.27690*4A

2019-02-21T20:20:25.389Z,1550780425.389 [Micromodem](CRITICAL): SIGSEGV: address not mapped to object
2019-02-21T20:20:25.389Z,1550780425.389 [Micromodem](CRITICAL): Bad address: 0x8
2019-02-21T20:20:25.389Z,1550780425.389 [Micromodem](CRITICAL): Backtrace: 
bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1780c8]
bin/LRAUV(_ZN9Component7executeEv+0x31c) [0xd60b4]
bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x177c64]

2019-02-21T20:20:25.389Z,1550780425.389 [Micromodem] Software Fault, FailCount= 2
2019-02-21T20:20:25.389Z,1550780425.389 [Micromodem](ERROR): Software Fault
2019-02-21T20:20:25.421Z,1550780425.421 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T20:20:25.421Z,1550780425.421 [SyncHandler](INFO): Protected caller Thread ID is 3087
2019-02-21T20:20:25.499Z,1550780425.499 [CBIT](INFO): Critical error at 20190221T202025
2019-02-21T20:20:25.502Z,1550780425.502 [CBIT](ERROR): Software Fault in component: Micromodem
2019-02-21T20:20:25.776Z,1550780425.776 [Micromodem](INFO): Stop
2019-02-21T20:20:25.776Z,1550780425.776 [Micromodem](INFO): uninitialize
2019-02-21T20:20:25.776Z,1550780425.776 [Micromodem](INFO): Powering down
2019-02-21T20:20:26.178Z,1550780426.178 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-02-21T20:20:26.189Z,1550780426.189 [Micromodem](INFO): Stopping
2019-02-21T20:20:26.582Z,1550780426.582 [Micromodem](INFO): Stopped
2019-02-21T20:20:26.617Z,1550780426.617 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T20:20:26.617Z,1550780426.617 [Micromodem] No Fault, FailCount= 2
2019-02-21T20:20:26.994Z,1550780426.994 [Micromodem](INFO): Start
2019-02-21T20:20:27.390Z,1550780427.390 [Micromodem](INFO): Start
2019-02-21T20:20:27.794Z,1550780427.794 [Micromodem](INFO): Start
2019-02-21T20:20:28.198Z,1550780428.198 [Micromodem](INFO): Start
2019-02-21T20:20:28.602Z,1550780428.602 [Micromodem](INFO): Start
2019-02-21T20:20:28.853Z,1550780428.853 [CommandLine](IMPORTANT): got command quit
2019-02-21T20:20:29.003Z,1550780429.003 [Micromodem](INFO): Start
2019-02-21T20:20:29.410Z,1550780429.410 [Micromodem](INFO): Starting
2019-02-21T20:20:29.410Z,1550780429.410 [Micromodem](INFO): Powering up
2019-02-21T20:20:29.410Z,1550780429.410 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:20:29.814Z,1550780429.814 [Micromodem](INFO): Starting
2019-02-21T20:20:29.857Z,1550780429.857 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:20:29.857Z,1550780429.857 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:29.989Z,1550780429.989 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-21T20:20:29.989Z,1550780429.989 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:29.989Z,1550780429.989 [CommandLine](INFO): Join timeout helper Thread ID is 3089
2019-02-21T20:20:30.005Z,1550780430.005 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-21T20:20:30.005Z,1550780430.005 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.005Z,1550780430.005 [NavChartDb](INFO): Join timeout helper Thread ID is 3090
2019-02-21T20:20:30.009Z,1550780430.009 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:20:30.009Z,1550780430.009 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.010Z,1550780430.010 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-21T20:20:30.010Z,1550780430.010 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.010Z,1550780430.010 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 3091
2019-02-21T20:20:30.133Z,1550780430.133 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:20:30.133Z,1550780430.133 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T20:20:30.134Z,1550780430.134 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.153Z,1550780430.153 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-21T20:20:30.153Z,1550780430.153 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.153Z,1550780430.153 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3092
2019-02-21T20:20:30.197Z,1550780430.197 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:20:30.197Z,1550780430.197 [CTD_NeilBrown](INFO): Powering down
2019-02-21T20:20:30.218Z,1550780430.218 [Micromodem](INFO): Starting
2019-02-21T20:20:30.219Z,1550780430.219 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.233Z,1550780430.233 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-21T20:20:30.233Z,1550780430.233 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.233Z,1550780430.233 [Radio_Surface](INFO): Join timeout helper Thread ID is 3093
2019-02-21T20:20:30.493Z,1550780430.493 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:20:30.493Z,1550780430.493 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.498Z,1550780430.498 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-21T20:20:30.498Z,1550780430.498 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.498Z,1550780430.498 [logger](INFO): Join timeout helper Thread ID is 3094
2019-02-21T20:20:30.561Z,1550780430.561 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:20:30.561Z,1550780430.561 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.577Z,1550780430.577 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-21T20:20:30.577Z,1550780430.577 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.578Z,1550780430.578 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-21T20:20:30.578Z,1550780430.578 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:30.578Z,1550780430.578 [controlThread](INFO): Join timeout helper Thread ID is 3095
2019-02-21T20:20:30.613Z,1550780430.613 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:20:30.613Z,1550780430.613 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-21T20:20:30.614Z,1550780430.614 [DUSBL_Hydroid](INFO): Powering down
2019-02-21T20:20:30.705Z,1550780430.705 [Micromodem](INFO): uninitialize
2019-02-21T20:20:30.705Z,1550780430.705 [Micromodem](INFO): Powering down
2019-02-21T20:20:30.801Z,1550780430.801 [NAL9602](INFO): Powering down
2019-02-21T20:20:30.893Z,1550780430.893 [RDI_Pathfinder](INFO): Powering down
2019-02-21T20:20:30.894Z,1550780430.894 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T20:20:30.895Z,1550780430.895 [Aanderaa_O2](INFO): Powering down
2019-02-21T20:20:30.896Z,1550780430.896 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-21T20:20:30.897Z,1550780430.897 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-21T20:20:30.898Z,1550780430.898 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-21T20:20:30.899Z,1550780430.899 [MissionManager](INFO): Uninitializing Mission Default
2019-02-21T20:20:30.901Z,1550780430.901 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-21T20:20:30.902Z,1550780430.902 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-21T20:20:30.903Z,1550780430.903 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-21T20:20:30.903Z,1550780430.903 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-21T20:20:30.903Z,1550780430.903 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-21T20:20:30.903Z,1550780430.903 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-21T20:20:30.904Z,1550780430.904 [BuoyancyServo](INFO): Powering down
2019-02-21T20:20:30.917Z,1550780430.917 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-21T20:20:30.917Z,1550780430.917 [ElevatorServo](INFO): Powering down
2019-02-21T20:20:30.936Z,1550780430.936 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-21T20:20:30.936Z,1550780430.936 [MassServo](INFO): Powering down
2019-02-21T20:20:30.937Z,1550780430.937 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-21T20:20:30.937Z,1550780430.937 [RudderServo](INFO): Powering down
2019-02-21T20:20:30.938Z,1550780430.938 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-21T20:20:30.938Z,1550780430.938 [ThrusterServo](INFO): Powering down
2019-02-21T20:20:30.939Z,1550780430.939 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-21T20:20:30.939Z,1550780430.939 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-21T20:20:30.939Z,1550780430.939 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-21T20:20:30.939Z,1550780430.939 [CBIT](DEBUG): Powering off loads.
2019-02-21T20:20:30.953Z,1550780430.953 [CBIT](DEBUG): Disabling WDT.
2019-02-21T20:20:30.965Z,1550780430.965 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-21T20:20:30.965Z,1550780430.965 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:31.019Z,1550780431.019 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:31.186Z,1550780431.186 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:31.188Z,1550780431.188 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:31.243Z,1550780431.243 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:20:31.285Z,1550780431.285 [logger ThreadHandler](INFO): Thread cancelled.