2020-02-08T23:40:47.013Z,1581205247.013 [Supervisor](DEBUG): Initializing supervisor.
2020-02-08T23:40:47.016Z,1581205247.016 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-02-08T23:40:47.017Z,1581205247.017 [SyncHandler](INFO): Protected caller Thread ID is 1814
2020-02-08T23:40:47.017Z,1581205247.017 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-02-08T23:40:47.018Z,1581205247.018 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-02-08T23:40:47.019Z,1581205247.019 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1815
2020-02-08T23:40:47.022Z,1581205247.022 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-02-08T23:40:47.034Z,1581205247.034 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-02-08T23:40:47.035Z,1581205247.035 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-02-08T23:40:47.035Z,1581205247.035 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1816
2020-02-08T23:40:47.036Z,1581205247.036 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-02-08T23:40:47.037Z,1581205247.037 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-02-08T23:40:47.037Z,1581205247.037 [logger ThreadHandler](INFO): Protected caller Thread ID is 1817
2020-02-08T23:40:47.039Z,1581205247.039 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-02-08T23:40:47.039Z,1581205247.039 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-02-08T23:40:47.041Z,1581205247.041 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-02-08T23:40:47.473Z,1581205247.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-02-08T23:40:47.473Z,1581205247.473 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-02-08T23:40:47.615Z,1581205247.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-02-08T23:40:47.615Z,1581205247.615 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-02-08T23:40:47.999Z,1581205247.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-02-08T23:40:47.999Z,1581205247.999 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-02-08T23:40:48.136Z,1581205248.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-02-08T23:40:48.137Z,1581205248.137 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-02-08T23:40:48.327Z,1581205248.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-02-08T23:40:48.328Z,1581205248.328 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-02-08T23:40:48.772Z,1581205248.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-02-08T23:40:48.773Z,1581205248.773 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-02-08T23:40:48.988Z,1581205248.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-02-08T23:40:48.988Z,1581205248.988 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-02-08T23:40:49.135Z,1581205249.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-02-08T23:40:49.135Z,1581205249.135 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-02-08T23:40:49.324Z,1581205249.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-02-08T23:40:49.325Z,1581205249.325 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-02-08T23:40:49.422Z,1581205249.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-02-08T23:40:49.423Z,1581205249.423 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-02-08T23:40:49.721Z,1581205249.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-02-08T23:40:49.722Z,1581205249.722 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-02-08T23:40:49.802Z,1581205249.802 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-02-08T23:40:49.905Z,1581205249.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-02-08T23:40:49.905Z,1581205249.905 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-02-08T23:40:50.560Z,1581205250.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-02-08T23:40:50.560Z,1581205250.560 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-02-08T23:40:50.968Z,1581205250.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-02-08T23:40:50.970Z,1581205250.970 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-02-08T23:40:50.971Z,1581205250.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-02-08T23:40:51.179Z,1581205251.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-02-08T23:40:51.278Z,1581205251.278 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-02-08T23:40:51.377Z,1581205251.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-02-08T23:40:51.601Z,1581205251.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-02-08T23:40:51.602Z,1581205251.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-02-08T23:40:51.687Z,1581205251.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-02-08T23:40:51.782Z,1581205251.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-02-08T23:40:51.879Z,1581205251.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-02-08T23:40:51.982Z,1581205251.982 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-02-08T23:40:52.089Z,1581205252.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-02-08T23:40:52.183Z,1581205252.183 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-02-08T23:40:52.366Z,1581205252.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-02-08T23:40:52.496Z,1581205252.496 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-02-08T23:40:52.503Z,1581205252.503 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-02-08T23:40:52.808Z,1581205252.808 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-02-08T23:40:52.813Z,1581205252.813 [AHRS_M2](INFO): created writer for : platform_orientation
2020-02-08T23:40:52.815Z,1581205252.815 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-02-08T23:40:52.819Z,1581205252.819 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-02-08T23:40:52.820Z,1581205252.820 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-02-08T23:40:52.825Z,1581205252.825 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-02-08T23:40:52.825Z,1581205252.825 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-02-08T23:40:52.830Z,1581205252.830 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-02-08T23:40:52.898Z,1581205252.898 [AHRS_M2] Loaded
2020-02-08T23:40:52.898Z,1581205252.898 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-02-08T23:40:52.980Z,1581205252.980 [DataOverHttps] Loaded
2020-02-08T23:40:52.980Z,1581205252.980 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-02-08T23:40:52.982Z,1581205252.982 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-02-08T23:40:52.982Z,1581205252.982 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1900
2020-02-08T23:40:53.005Z,1581205253.005 [DDM] Loaded
2020-02-08T23:40:53.005Z,1581205253.005 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-02-08T23:40:53.018Z,1581205253.018 [Depth_Keller] Loaded
2020-02-08T23:40:53.018Z,1581205253.018 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-02-08T23:40:53.022Z,1581205253.022 [DropWeight] Loaded
2020-02-08T23:40:53.023Z,1581205253.023 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-02-08T23:40:53.082Z,1581205253.082 [DUSBL_Hydroid] Loaded
2020-02-08T23:40:53.082Z,1581205253.082 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-02-08T23:40:53.127Z,1581205253.127 [Micromodem] Loaded
2020-02-08T23:40:53.127Z,1581205253.127 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-02-08T23:40:53.220Z,1581205253.220 [NAL9602] Loaded
2020-02-08T23:40:53.220Z,1581205253.220 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-02-08T23:40:53.257Z,1581205253.257 [Onboard] Loaded
2020-02-08T23:40:53.258Z,1581205253.258 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-02-08T23:40:53.263Z,1581205253.263 [PowerOnly] Loaded
2020-02-08T23:40:53.263Z,1581205253.263 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-02-08T23:40:53.270Z,1581205253.270 [Radio_Surface] Loaded
2020-02-08T23:40:53.270Z,1581205253.270 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-02-08T23:40:53.271Z,1581205253.271 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-02-08T23:40:53.271Z,1581205253.271 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1901
2020-02-08T23:40:53.313Z,1581205253.313 [RDI_Pathfinder] Loaded
2020-02-08T23:40:53.313Z,1581205253.313 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-02-08T23:40:55.296Z,1581205255.296 [BPC1] Loaded
2020-02-08T23:40:55.296Z,1581205255.296 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-02-08T23:40:55.297Z,1581205255.297 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-02-08T23:40:55.297Z,1581205255.297 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-02-08T23:40:55.310Z,1581205255.310 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-02-08T23:40:55.310Z,1581205255.310 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-02-08T23:40:55.424Z,1581205255.424 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-02-08T23:40:55.425Z,1581205255.425 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-02-08T23:40:55.444Z,1581205255.444 [NavChart] Loaded
2020-02-08T23:40:55.445Z,1581205255.445 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-02-08T23:40:55.449Z,1581205255.449 [UniversalFixResidualReporter] Loaded
2020-02-08T23:40:55.449Z,1581205255.449 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-02-08T23:40:55.462Z,1581205255.462 [WorkSite] Loaded
2020-02-08T23:40:55.463Z,1581205255.463 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2020-02-08T23:40:55.463Z,1581205255.463 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-02-08T23:40:55.464Z,1581205255.464 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-02-08T23:40:55.643Z,1581205255.643 [BuoyancyServo] Loaded
2020-02-08T23:40:55.643Z,1581205255.643 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-02-08T23:40:55.658Z,1581205255.658 [ElevatorServo] Loaded
2020-02-08T23:40:55.658Z,1581205255.658 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-02-08T23:40:55.672Z,1581205255.672 [MassServo] Loaded
2020-02-08T23:40:55.672Z,1581205255.672 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-02-08T23:40:55.687Z,1581205255.687 [RudderServo] Loaded
2020-02-08T23:40:55.688Z,1581205255.688 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-02-08T23:40:55.702Z,1581205255.702 [ThrusterServo] Loaded
2020-02-08T23:40:55.702Z,1581205255.702 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-02-08T23:40:55.703Z,1581205255.703 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-02-08T23:40:55.703Z,1581205255.703 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-02-08T23:40:55.893Z,1581205255.893 [PAR_Licor] Loaded
2020-02-08T23:40:55.893Z,1581205255.893 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-02-08T23:40:55.935Z,1581205255.935 [WetLabsSeaOWL_UV_A] Loaded
2020-02-08T23:40:55.935Z,1581205255.935 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-02-08T23:40:55.936Z,1581205255.936 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F04E0
2020-02-08T23:40:55.937Z,1581205255.937 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1903
2020-02-08T23:40:55.937Z,1581205255.937 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-02-08T23:40:55.938Z,1581205255.938 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-02-08T23:40:56.276Z,1581205256.276 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-02-08T23:40:56.276Z,1581205256.276 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-02-08T23:40:56.315Z,1581205256.315 [DepthRateCalculator] Loaded
2020-02-08T23:40:56.316Z,1581205256.316 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-02-08T23:40:56.321Z,1581205256.321 [PitchRateCalculator] Loaded
2020-02-08T23:40:56.321Z,1581205256.321 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-02-08T23:40:56.333Z,1581205256.333 [SpeedCalculator] Loaded
2020-02-08T23:40:56.333Z,1581205256.333 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-02-08T23:40:56.354Z,1581205256.354 [TempGradientCalculator] Loaded
2020-02-08T23:40:56.354Z,1581205256.354 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-02-08T23:40:56.359Z,1581205256.359 [YawRateCalculator] Loaded
2020-02-08T23:40:56.359Z,1581205256.359 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-02-08T23:40:56.398Z,1581205256.398 [ElevatorOffsetCalculator] Loaded
2020-02-08T23:40:56.398Z,1581205256.398 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-02-08T23:40:56.399Z,1581205256.399 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-02-08T23:40:56.399Z,1581205256.399 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-02-08T23:40:56.534Z,1581205256.534 [SBIT](DEBUG): Construct Startup Built In Test.
2020-02-08T23:40:56.555Z,1581205256.555 [SBIT] Loaded
2020-02-08T23:40:56.555Z,1581205256.555 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-02-08T23:40:56.556Z,1581205256.556 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-02-08T23:40:56.568Z,1581205256.568 [IBIT] Loaded
2020-02-08T23:40:56.568Z,1581205256.568 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-02-08T23:40:56.571Z,1581205256.571 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-02-08T23:40:56.705Z,1581205256.705 [CBIT] Loaded
2020-02-08T23:40:56.705Z,1581205256.705 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-02-08T23:40:56.705Z,1581205256.705 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-02-08T23:40:56.706Z,1581205256.706 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-02-08T23:40:56.785Z,1581205256.785 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-02-08T23:40:56.786Z,1581205256.786 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-02-08T23:40:56.880Z,1581205256.880 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-02-08T23:40:56.881Z,1581205256.881 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-02-08T23:40:56.927Z,1581205256.927 [VerticalControl](DEBUG): Construct VerticalControl.
2020-02-08T23:40:57.007Z,1581205257.007 [VerticalControl] Loaded
2020-02-08T23:40:57.007Z,1581205257.007 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-02-08T23:40:57.008Z,1581205257.008 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-02-08T23:40:57.075Z,1581205257.075 [HorizontalControl] Loaded
2020-02-08T23:40:57.075Z,1581205257.075 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-02-08T23:40:57.075Z,1581205257.075 [SpeedControl](DEBUG): Construct SpeedControl.
2020-02-08T23:40:57.077Z,1581205257.077 [SpeedControl] Loaded
2020-02-08T23:40:57.077Z,1581205257.077 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-02-08T23:40:57.078Z,1581205257.078 [LoopControl](DEBUG): Construct LoopControl.
2020-02-08T23:40:57.078Z,1581205257.078 [LoopControl] Loaded
2020-02-08T23:40:57.079Z,1581205257.079 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-02-08T23:40:57.079Z,1581205257.079 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-02-08T23:40:57.080Z,1581205257.080 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-02-08T23:40:57.111Z,1581205257.111 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-02-08T23:40:57.114Z,1581205257.114 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-02-08T23:40:57.115Z,1581205257.115 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-02-08T23:40:57.121Z,1581205257.121 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-02-08T23:40:57.123Z,1581205257.123 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0
2020-02-08T23:40:57.123Z,1581205257.123 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1904
2020-02-08T23:40:57.127Z,1581205257.127 [Supervisor](INFO): Main Thread ID is 816
2020-02-08T23:40:57.128Z,1581205257.128 [Supervisor](DEBUG): Running supervisor.
2020-02-08T23:40:57.128Z,1581205257.128 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1905
2020-02-08T23:40:57.131Z,1581205257.131 [controlThread ThreadHandler](INFO): Handler Thread ID is 1906
2020-02-08T23:40:57.131Z,1581205257.131 [controlThread](DEBUG): Initializing ControlThread
2020-02-08T23:40:57.136Z,1581205257.136 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-02-08T23:40:57.136Z,1581205257.136 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-02-08T23:40:57.137Z,1581205257.137 [WorkSite](DEBUG): Initializing WorkSite component.
2020-02-08T23:40:57.141Z,1581205257.141 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-02-08T23:40:57.141Z,1581205257.141 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-02-08T23:40:57.142Z,1581205257.142 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-02-08T23:40:57.142Z,1581205257.142 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-02-08T23:40:57.143Z,1581205257.143 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-02-08T23:40:57.143Z,1581205257.143 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-02-08T23:40:57.144Z,1581205257.144 [SBIT](INFO): Initialize SBIT Component.
2020-02-08T23:40:57.144Z,1581205257.144 [SBIT](IMPORTANT): git: 2020-02-07
2020-02-08T23:40:57.144Z,1581205257.144 [SBIT](INFO): git hash: 6707874467016a4fb4c344b7e74bc4738258579e
2020-02-08T23:40:57.145Z,1581205257.145 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-02-08T23:40:57.146Z,1581205257.146 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-02-08T23:40:57.147Z,1581205257.147 [SBIT](INFO): Beginning SBIT in 29.000000 seconds.
2020-02-08T23:40:57.148Z,1581205257.148 [IBIT](INFO): Initialize IBIT Component.
2020-02-08T23:40:57.148Z,1581205257.148 [CBIT](DEBUG): Initialize CBIT Component.
2020-02-08T23:40:57.149Z,1581205257.149 [logger ThreadHandler](INFO): Handler Thread ID is 1907
2020-02-08T23:40:57.161Z,1581205257.161 [CBIT](DEBUG): Initialized mux pins.
2020-02-08T23:40:57.161Z,1581205257.161 [CBIT](DEBUG): Initializing the watchdog timer.
2020-02-08T23:40:57.169Z,1581205257.169 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1908
2020-02-08T23:40:57.170Z,1581205257.170 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-02-08T23:40:57.181Z,1581205257.181 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1909
2020-02-08T23:40:57.185Z,1581205257.185 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-02-08T23:40:57.185Z,1581205257.185 [CBIT](DEBUG): Initializing heartbeat.
2020-02-08T23:40:57.193Z,1581205257.193 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1910
2020-02-08T23:40:57.194Z,1581205257.194 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-08T23:40:57.221Z,1581205257.221 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1911
2020-02-08T23:40:57.224Z,1581205257.224 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-02-08T23:40:57.224Z,1581205257.224 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-02-08T23:40:57.225Z,1581205257.225 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-02-08T23:40:57.225Z,1581205257.225 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-02-08T23:40:57.225Z,1581205257.225 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-02-08T23:40:57.225Z,1581205257.225 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-02-08T23:40:57.225Z,1581205257.225 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-02-08T23:40:57.225Z,1581205257.225 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-02-08T23:40:57.226Z,1581205257.226 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-02-08T23:40:57.226Z,1581205257.226 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-02-08T23:40:57.226Z,1581205257.226 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-02-08T23:40:57.226Z,1581205257.226 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-02-08T23:40:57.226Z,1581205257.226 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-02-08T23:40:57.226Z,1581205257.226 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-02-08T23:40:57.227Z,1581205257.227 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-02-08T23:40:57.227Z,1581205257.227 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-02-08T23:40:57.257Z,1581205257.257 [CBIT](DEBUG): Deactivating GF circuits.
2020-02-08T23:40:57.257Z,1581205257.257 [CBIT](DEBUG): Deactivating emergency mode.
2020-02-08T23:40:57.293Z,1581205257.293 [CBIT](DEBUG): Backplane powered.
2020-02-08T23:40:57.293Z,1581205257.293 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-02-08T23:40:57.295Z,1581205257.295 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-02-08T23:40:57.295Z,1581205257.295 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-02-08T23:40:57.296Z,1581205257.296 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-02-08T23:40:57.297Z,1581205257.297 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-02-08T23:40:57.307Z,1581205257.307 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-08T23:40:57.329Z,1581205257.329 [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>


2020-02-08T23:40:57.330Z,1581205257.330 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2020-02-08T23:40:57.421Z,1581205257.421 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2020-02-08T23:40:57.432Z,1581205257.432 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2020-02-08T23:40:57.436Z,1581205257.436 [MissionManager](INFO): DefineArg DefaultWithUndock.MicromodemCommsInterval = 2.000000 min
2020-02-08T23:40:57.461Z,1581205257.461 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2020-02-08T23:40:57.463Z,1581205257.463 [DefaultWithUndock:B.Undock](DEBUG): Construct.
2020-02-08T23:40:57.488Z,1581205257.488 [DefaultWithUndock:C.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-08T23:40:57.506Z,1581205257.506 [DefaultWithUndock:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-02-08T23:40:57.533Z,1581205257.533 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-02-08T23:40:57.540Z,1581205257.540 [DefaultWithUndock:F.Execute](DEBUG): Construct Execute.
2020-02-08T23:40:57.560Z,1581205257.560 [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="DefaultWithUndock">

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

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to reach the approach depth after detaching form the dock.
        The vehicle will switch over to midcourse guidance after the timeout is expired.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="MicromodemCommsInterval"><Description>
        How often to trigger Umodem communications
    </Description><Units:minute/><Value>2</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>30</Value></Setting>
    </Guidance:Wait>

    <!-- Leave the dock if necessary --> 
    <Guidance:Undock>
        <Sequence/>
        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>
    </Guidance:Undock>

    <!-- This mission assumes open water so from here on it's the same old default that's always used -->
    <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>


2020-02-08T23:40:57.565Z,1581205257.565 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-02-08T23:40:57.585Z,1581205257.585 [Radio_Surface](INFO): Powering up
2020-02-08T23:40:57.586Z,1581205257.586 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-08T23:40:57.626Z,1581205257.626 [DDM](INFO): Powering up
2020-02-08T23:40:57.626Z,1581205257.626 [DDM](DEBUG): Initializing DDM.
2020-02-08T23:40:57.648Z,1581205257.648 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2020-02-08T23:40:57.648Z,1581205257.648 [DUSBL_Hydroid](INFO): Powering up
2020-02-08T23:40:57.649Z,1581205257.649 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-02-08T23:40:57.869Z,1581205257.869 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-08T23:40:57.895Z,1581205257.895 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-08T23:40:57.901Z,1581205257.901 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-08T23:40:57.902Z,1581205257.902 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T23:40:57.909Z,1581205257.909 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T23:40:57.910Z,1581205257.910 [MassServo](DEBUG): Initializing EZServoServo.
2020-02-08T23:40:57.917Z,1581205257.917 [MassServo](DEBUG): Initializing MassServo.
2020-02-08T23:40:57.918Z,1581205257.918 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-08T23:40:57.925Z,1581205257.925 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-08T23:40:57.926Z,1581205257.926 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-02-08T23:40:57.933Z,1581205257.933 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-02-08T23:40:57.995Z,1581205257.995 [Micromodem](INFO): Powering up
2020-02-08T23:40:57.995Z,1581205257.995 [Micromodem](DEBUG): Initializing Micromodem.
2020-02-08T23:40:58.065Z,1581205258.065 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-08T23:40:58.761Z,1581205258.761 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-02-08T23:40:58.761Z,1581205258.761 [RudderServo](FAULT): Rudder failed to initialize
2020-02-08T23:40:58.761Z,1581205258.761 [RudderServo] Communications Fault, FailCount= 1
2020-02-08T23:40:58.761Z,1581205258.761 [RudderServo](ERROR): Communications Fault
2020-02-08T23:40:58.876Z,1581205258.876 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-02-08T23:40:59.082Z,1581205259.082 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-08T23:40:59.082Z,1581205259.082 [RudderServo](INFO): Powering down
2020-02-08T23:40:59.751Z,1581205259.751 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-08T23:40:59.870Z,1581205259.870 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-08T23:40:59.874Z,1581205259.874 [CBIT](INFO): Clearing failed state for component RudderServo
2020-02-08T23:40:59.874Z,1581205259.874 [RudderServo] No Fault, FailCount= 1
2020-02-08T23:41:02.262Z,1581205262.262 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2020-02-08T23:41:02.264Z,1581205262.264 [WorkSite](ERROR): Failed to parse: 

2020-02-08T23:41:02.265Z,1581205262.265 [WorkSite](IMPORTANT): WorkSite fix at 20200208T222546: (38.837070, -122.564635)
2020-02-08T23:41:02.963Z,1581205262.963 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-02-08T23:41:05.811Z,1581205265.811 [Micromodem](INFO): Nmea in: $CATMG,2020-02-08T23:41:05.355660Z,RTC,RTC*58

2020-02-08T23:41:05.812Z,1581205265.812 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-02-08T23:41:05.355660Z,RTC,RTC*58

2020-02-08T23:41:11.072Z,1581205271.072 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-08T23:41:13.063Z,1581205273.063 [DDM](INFO): Dynamic Docking Module:
2020-02-08T23:41:14.279Z,1581205274.279 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-02-08T23:41:15.087Z,1581205275.087 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-02-08T23:41:15.087Z,1581205275.087 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-02-08T23:41:15.490Z,1581205275.490 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-02-08T23:41:15.502Z,1581205275.502 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-02-08T23:41:15.502Z,1581205275.502 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-02-08T23:41:15.895Z,1581205275.895 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-02-08T23:41:15.895Z,1581205275.895 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-02-08T23:41:16.299Z,1581205276.299 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-02-08T23:41:16.299Z,1581205276.299 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-02-08T23:41:16.703Z,1581205276.703 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-02-08T23:41:16.703Z,1581205276.703 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2020-02-08T23:41:17.107Z,1581205277.107 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2020-02-08T23:41:17.107Z,1581205277.107 [Micromodem](INFO): Nmea out: $CCCFG,BW0,2000*65

2020-02-08T23:41:17.510Z,1581205277.510 [Micromodem](INFO): Nmea in: $CACFG,BW0,2000*67

2020-02-08T23:41:17.511Z,1581205277.511 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-02-08T23:41:17.919Z,1581205277.919 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-02-08T23:41:17.920Z,1581205277.920 [Micromodem](INFO): Nmea out: $CCCLK,2020,02,08,23,41,18*43

2020-02-08T23:41:18.319Z,1581205278.319 [Micromodem](INFO): Nmea in: $CACLK,2020,2,8,23,41,18*41

2020-02-08T23:41:18.763Z,1581205278.763 [Micromodem](INFO): Nmea in: $CATMS,0,2020-02-08T23:41:19Z*70

2020-02-08T23:41:18.764Z,1581205278.764 [Micromodem](INFO): Nmea in: $CATMG,2020-02-08T23:41:19.026044Z,USER_CMD,RTC*13

2020-02-08T23:41:23.571Z,1581205283.571 [NAL9602](INFO): Powering up NAL9602
2020-02-08T23:41:26.880Z,1581205286.880 [SBIT](IMPORTANT): Beginning Startup BIT
2020-02-08T23:41:26.888Z,1581205286.888 [CBIT](IMPORTANT): Beginning ground fault scan
2020-02-08T23:41:34.703Z,1581205294.703 [NAL9602](INFO): NAL9602 initialized
2020-02-08T23:41:38.105Z,1581205298.105 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.016015
CHAN A1 (24V): -0.028052
CHAN A2 (12V): -0.007121
CHAN A3 (5V): -0.002425
CHAN B0 (3.3V): -0.000024
CHAN B1 (3.15aV): 0.000013
CHAN B2 (3.15bV): 0.000785
CHAN B3 (GND): 0.001889
OPEN: 0.005106
Full Scale Calc: 4.765 mA, -1.589 mA
2020-02-08T23:41:48.367Z,1581205308.367 [CommandLine](IMPORTANT): got command strobe off
2020-02-08T23:41:48.367Z,1581205308.367 [CommandLine](IMPORTANT): Deactivating strobe
2020-02-08T23:42:05.931Z,1581205325.931 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T23:42:20.615Z,1581205340.615 [SBIT](IMPORTANT): SBIT PASSED
2020-02-08T23:42:20.615Z,1581205340.615 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-02-08T23:42:20.616Z,1581205340.616 [SBIT](IMPORTANT): DUSBL_Hydroid.verbosity=3 enum;

2020-02-08T23:42:20.616Z,1581205340.616 [SBIT](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 10.000000 meter;

2020-02-08T23:42:20.616Z,1581205340.616 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2020-02-08T23:42:20.617Z,1581205340.617 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=320 cubic_centimeter;

2020-02-08T23:42:20.617Z,1581205340.617 [SBIT](IMPORTANT): VerticalControl.massDefault=2.4 millimeter;

2020-02-08T23:42:21.025Z,1581205341.025 [MissionManager](IMPORTANT): Started mission Startup
2020-02-08T23:42:21.025Z,1581205341.025 [Startup] Running Loop=1
2020-02-08T23:42:21.026Z,1581205341.026 [Startup](DEBUG): Aggregate::initialize Startup
2020-02-08T23:42:21.026Z,1581205341.026 [Startup:A.GoToSurface] Running Loop=1
2020-02-08T23:42:21.026Z,1581205341.026 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-08T23:42:21.026Z,1581205341.026 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-08T23:42:21.027Z,1581205341.027 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-08T23:42:21.027Z,1581205341.027 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-08T23:42:21.027Z,1581205341.027 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-08T23:42:21.028Z,1581205341.028 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-08T23:42:21.029Z,1581205341.029 [Startup:StartupSatComms] Running Loop=1
2020-02-08T23:42:21.029Z,1581205341.029 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-02-08T23:42:21.030Z,1581205341.030 [Startup:StartupSatComms:A] Running Loop=1
2020-02-08T23:42:21.417Z,1581205341.417 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-02-08T23:43:05.238Z,1581205385.238 [CommandLine](IMPORTANT): got command get latitude
2020-02-08T23:43:05.239Z,1581205385.239 [CommandLine](IMPORTANT): latitude 38.837070 arcdeg
2020-02-08T23:43:21.217Z,1581205401.217 [Startup:StartupSatComms:A](INFO): Timed out from 2020-02-08T23:42:21.0Z
2020-02-08T23:43:21.217Z,1581205401.217 [Startup:StartupSatComms:A] Stopped
2020-02-08T23:43:21.217Z,1581205401.217 [Startup:StartupSatComms:B] Running Loop=1
2020-02-08T23:43:21.619Z,1581205401.619 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-08T23:43:28.158Z,1581205408.158 [CommandLine](IMPORTANT): got command get longitude
2020-02-08T23:43:28.158Z,1581205408.158 [CommandLine](IMPORTANT): longitude -122.564635 arcdeg
2020-02-08T23:44:21.541Z,1581205461.541 [Startup:StartupSatComms:B](INFO): Timed out from 2020-02-08T23:43:21.2Z
2020-02-08T23:44:21.541Z,1581205461.541 [Startup:StartupSatComms:B] Stopped
2020-02-08T23:44:21.541Z,1581205461.541 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-02-08T23:44:21.541Z,1581205461.541 [Startup:StartupSatComms] Stopped
2020-02-08T23:44:21.541Z,1581205461.541 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-02-08T23:44:21.542Z,1581205461.542 [Startup](INFO): Completed Startup
2020-02-08T23:44:21.542Z,1581205461.542 [MissionManager](INFO): Startup is completed.
2020-02-08T23:44:21.542Z,1581205461.542 [MissionManager](INFO): Uninitializing Mission Startup
2020-02-08T23:44:21.542Z,1581205461.542 [Startup] Stopped
2020-02-08T23:44:21.542Z,1581205461.542 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-02-08T23:44:21.543Z,1581205461.543 [Startup:A.GoToSurface] Stopped
2020-02-08T23:44:21.543Z,1581205461.543 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-08T23:44:21.926Z,1581205461.926 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-02-08T23:44:21.926Z,1581205461.926 [DefaultWithUndock] Running Loop=1
2020-02-08T23:44:21.926Z,1581205461.926 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-02-08T23:44:21.927Z,1581205461.927 [DefaultWithUndock:C.GoToSurface] Running Loop=1
2020-02-08T23:44:21.927Z,1581205461.927 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-08T23:44:21.927Z,1581205461.927 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-08T23:44:21.927Z,1581205461.927 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-08T23:44:21.927Z,1581205461.927 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-08T23:44:21.928Z,1581205461.928 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-08T23:44:21.928Z,1581205461.928 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-08T23:44:21.928Z,1581205461.928 [DefaultWithUndock:A.Wait] Running Loop=1
2020-02-08T23:44:21.928Z,1581205461.928 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-02-08T23:44:37.454Z,1581205477.454 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003747
2020-02-08T23:44:52.220Z,1581205492.220 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-02-08T23:44:52.220Z,1581205492.220 [DefaultWithUndock:A.Wait] Stopped
2020-02-08T23:44:52.220Z,1581205492.220 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T23:44:52.221Z,1581205492.221 [DefaultWithUndock:B.Undock] Running Loop=1
2020-02-08T23:44:52.221Z,1581205492.221 [DefaultWithUndock:B.Undock](DEBUG): Initialize.
2020-02-08T23:44:52.996Z,1581205492.996 [PowerOnly](INFO): Powering up
2020-02-08T23:45:13.679Z,1581205513.679 [DefaultWithUndock:B.Undock](INFO): Detaching from dock.
2020-02-08T23:45:14.071Z,1581205514.071 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer.
2020-02-08T23:45:34.248Z,1581205534.248 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-02-08T23:45:52.464Z,1581205552.464 [DefaultWithUndock:B.Undock](INFO): DDM at standby.
2020-02-08T23:45:52.901Z,1581205552.901 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-02-08T23:45:52.901Z,1581205552.901 [DefaultWithUndock:B.Undock] Stopped
2020-02-08T23:45:53.221Z,1581205553.221 [DefaultWithUndock:CheckIn] Running Loop=1
2020-02-08T23:45:53.221Z,1581205553.221 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T23:45:53.221Z,1581205553.221 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T23:45:53.654Z,1581205553.654 [DefaultWithUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-02-08T23:45:57.238Z,1581205557.238 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T23:45:57.239Z,1581205557.239 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020816585680,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-08T23:46:06.838Z,1581205566.838 [CommandLine](IMPORTANT): got command get latitude
2020-02-08T23:46:06.840Z,1581205566.840 [CommandLine](IMPORTANT): latitude 38.837070 arcdeg
2020-02-08T23:46:09.115Z,1581205569.115 [CommandLine](IMPORTANT): got command 
2020-02-08T23:46:09.115Z,1581205569.115 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-02-08T23:46:12.413Z,1581205572.413 [CommandLine](IMPORTANT): got command failComponent
2020-02-08T23:46:12.414Z,1581205572.414 [CommandLine](IMPORTANT): Failed components:
2020-02-08T23:46:12.414Z,1581205572.414 [CommandLine](IMPORTANT): No failed Components.
2020-02-08T23:46:24.362Z,1581205584.362 [PowerOnly](INFO): Powering down
2020-02-08T23:46:40.522Z,1581205600.522 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T23:46:40.522Z,1581205600.522 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020816593, -0.1,   0.0,1448.9,  0
2020-02-08T23:47:35.461Z,1581205655.461 [NAL9602](INFO): SBD MO Status=2, MOMSN=10622, MT Status=2, MTMSN=0
2020-02-08T23:47:35.461Z,1581205655.461 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T23:47:35.852Z,1581205655.852 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-08T23:47:37.068Z,1581205657.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234720.00,A,4450.22722,N,06733.87095,W,0.194,6.88,080220,,,A*7E
2020-02-08T23:47:37.079Z,1581205657.079 [NAL9602](INFO): GPS fix at 20200208T234720: (44.837120, -67.564516)
2020-02-08T23:47:37.141Z,1581205657.141 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T23:47:37.141Z,1581205657.141 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T23:47:37.513Z,1581205657.513 [DefaultWithUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-08T23:47:44.548Z,1581205664.548 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20200208T230509/Courier0016.lzma
2020-02-08T23:47:45.234Z,1581205665.234 [CommandLine](IMPORTANT): got command get latitude
2020-02-08T23:47:45.235Z,1581205665.235 [CommandLine](IMPORTANT): latitude 44.837120 arcdeg
2020-02-08T23:47:46.554Z,1581205666.554 [DataOverHttps](INFO): Moved sent file to Logs/20200208T230509/Courier0016.lzma.bak
2020-02-08T23:47:46.554Z,1581205666.554 [DataOverHttps](INFO): SBD MOMSN=12238239
2020-02-08T23:48:04.165Z,1581205684.165 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20200208T234046/Courier0000.lzma
2020-02-08T23:48:06.166Z,1581205686.166 [DataOverHttps](INFO): Moved sent file to Logs/20200208T234046/Courier0000.lzma.bak
2020-02-08T23:48:06.166Z,1581205686.166 [DataOverHttps](INFO): SBD MOMSN=12238242
2020-02-08T23:48:09.048Z,1581205689.048 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T23:48:22.067Z,1581205702.067 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-08T23:48:22.075Z,1581205702.075 [BPC1](INFO): Received data from all battery sticks.
2020-02-08T23:48:26.696Z,1581205706.696 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T234046/Courier0004.lzma
2020-02-08T23:48:28.702Z,1581205708.702 [DataOverHttps](INFO): Moved sent file to Logs/20200208T234046/Courier0004.lzma.bak
2020-02-08T23:48:28.702Z,1581205708.702 [DataOverHttps](INFO): SBD MOMSN=12238245
2020-02-08T23:48:46.194Z,1581205726.194 [DataOverHttps](INFO): Sending 1237 bytes from file Logs/20200208T230509/Express0017.lzma
2020-02-08T23:48:48.198Z,1581205728.198 [DataOverHttps](INFO): Moved sent file to Logs/20200208T230509/Express0017.lzma.bak
2020-02-08T23:48:48.198Z,1581205728.198 [DataOverHttps](INFO): SBD MOMSN=12238248
2020-02-08T23:49:05.562Z,1581205745.562 [DataOverHttps](INFO): Sending 958 bytes from file Logs/20200208T234046/Express0001.lzma
2020-02-08T23:49:07.566Z,1581205747.566 [DataOverHttps](INFO): Moved sent file to Logs/20200208T234046/Express0001.lzma.bak
2020-02-08T23:49:07.566Z,1581205747.566 [DataOverHttps](INFO): SBD MOMSN=12238320
2020-02-08T23:49:25.305Z,1581205765.305 [DataOverHttps](INFO): Sending 415 bytes from file Logs/20200208T234046/Express0005.lzma
2020-02-08T23:49:27.310Z,1581205767.310 [DataOverHttps](INFO): Moved sent file to Logs/20200208T234046/Express0005.lzma.bak
2020-02-08T23:49:27.310Z,1581205767.310 [DataOverHttps](INFO): SBD MOMSN=12238343
2020-02-08T23:49:29.092Z,1581205769.092 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T23:49:29.092Z,1581205769.092 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T23:49:29.093Z,1581205769.093 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T23:49:42.275Z,1581205782.275 [CommandLine](IMPORTANT): got command restart application
2020-02-08T23:49:43.281Z,1581205783.281 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-02-08T23:49:43.281Z,1581205783.281 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:49:43.281Z,1581205783.281 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:43.353Z,1581205783.353 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-02-08T23:49:43.353Z,1581205783.353 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:43.353Z,1581205783.353 [CommandLine](INFO): Join timeout helper Thread ID is 1960
2020-02-08T23:49:43.354Z,1581205783.354 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-08T23:49:43.354Z,1581205783.354 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:43.354Z,1581205783.354 [NavChartDb](INFO): Join timeout helper Thread ID is 1961
2020-02-08T23:49:43.737Z,1581205783.737 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:49:43.737Z,1581205783.737 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:43.757Z,1581205783.757 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-02-08T23:49:43.757Z,1581205783.757 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:43.758Z,1581205783.758 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1962
2020-02-08T23:49:43.809Z,1581205783.809 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:49:43.809Z,1581205783.809 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-08T23:49:43.810Z,1581205783.810 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:43.819Z,1581205783.819 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-08T23:49:43.819Z,1581205783.819 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:43.819Z,1581205783.819 [Radio_Surface](INFO): Join timeout helper Thread ID is 1963
2020-02-08T23:49:44.133Z,1581205784.133 [Radio_Surface](INFO): Powering down
2020-02-08T23:49:44.134Z,1581205784.134 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:49:44.135Z,1581205784.135 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:44.137Z,1581205784.137 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-08T23:49:44.137Z,1581205784.137 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:44.138Z,1581205784.138 [DataOverHttps](INFO): Join timeout helper Thread ID is 1964
2020-02-08T23:49:45.069Z,1581205785.069 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:49:45.069Z,1581205785.069 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.086Z,1581205785.086 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-08T23:49:45.086Z,1581205785.086 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.086Z,1581205785.086 [logger](INFO): Join timeout helper Thread ID is 1965
2020-02-08T23:49:45.113Z,1581205785.113 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:49:45.113Z,1581205785.113 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.125Z,1581205785.125 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-08T23:49:45.126Z,1581205785.126 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.126Z,1581205785.126 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-08T23:49:45.126Z,1581205785.126 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.126Z,1581205785.126 [controlThread](INFO): Join timeout helper Thread ID is 1966
2020-02-08T23:49:45.198Z,1581205785.198 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:49:45.199Z,1581205785.199 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-08T23:49:45.201Z,1581205785.201 [AHRS_M2](INFO): Powering down
2020-02-08T23:49:45.273Z,1581205785.273 [DDM](INFO): Powering down
2020-02-08T23:49:45.361Z,1581205785.361 [DUSBL_Hydroid](INFO): Powering down
2020-02-08T23:49:45.453Z,1581205785.453 [Micromodem](INFO): Powering down
2020-02-08T23:49:45.549Z,1581205785.549 [NAL9602](INFO): Powering down
2020-02-08T23:49:45.621Z,1581205785.621 [RDI_Pathfinder](INFO): Powering down
2020-02-08T23:49:45.622Z,1581205785.622 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-08T23:49:45.623Z,1581205785.623 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-08T23:49:45.624Z,1581205785.624 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-08T23:49:45.624Z,1581205785.624 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2020-02-08T23:49:45.624Z,1581205785.624 [DefaultWithUndock] Stopped
2020-02-08T23:49:45.625Z,1581205785.625 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-02-08T23:49:45.625Z,1581205785.625 [DefaultWithUndock:C.GoToSurface] Stopped
2020-02-08T23:49:45.625Z,1581205785.625 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-08T23:49:45.625Z,1581205785.625 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T23:49:45.625Z,1581205785.625 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T23:49:45.625Z,1581205785.625 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T23:49:45.625Z,1581205785.625 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T23:49:45.628Z,1581205785.628 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-08T23:49:45.628Z,1581205785.628 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-08T23:49:45.629Z,1581205785.629 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-08T23:49:45.629Z,1581205785.629 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-08T23:49:45.629Z,1581205785.629 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-08T23:49:45.630Z,1581205785.630 [BuoyancyServo](INFO): Powering down
2020-02-08T23:49:45.641Z,1581205785.641 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T23:49:45.641Z,1581205785.641 [ElevatorServo](INFO): Powering down
2020-02-08T23:49:45.642Z,1581205785.642 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-02-08T23:49:45.642Z,1581205785.642 [MassServo](INFO): Powering down
2020-02-08T23:49:45.643Z,1581205785.643 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-08T23:49:45.643Z,1581205785.643 [RudderServo](INFO): Powering down
2020-02-08T23:49:45.643Z,1581205785.643 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-08T23:49:45.643Z,1581205785.643 [ThrusterServo](INFO): Powering down
2020-02-08T23:49:45.644Z,1581205785.644 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-08T23:49:45.645Z,1581205785.645 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-08T23:49:45.645Z,1581205785.645 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-08T23:49:45.645Z,1581205785.645 [CBIT](DEBUG): Powering off loads.
2020-02-08T23:49:45.657Z,1581205785.657 [CBIT](DEBUG): Disabling WDT.
2020-02-08T23:49:45.669Z,1581205785.669 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-08T23:49:45.670Z,1581205785.670 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.722Z,1581205785.722 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.733Z,1581205785.733 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.775Z,1581205785.775 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.833Z,1581205785.833 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:49:45.900Z,1581205785.900 [logger ThreadHandler](INFO): Thread cancelled.