2019-05-07T21:32:23.609Z,1557264743.609 [Supervisor](DEBUG): Initializing supervisor.
2019-05-07T21:32:23.612Z,1557264743.612 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-07T21:32:23.613Z,1557264743.613 [SyncHandler](INFO): Protected caller Thread ID is 810
2019-05-07T21:32:23.613Z,1557264743.613 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-07T21:32:23.614Z,1557264743.614 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-07T21:32:23.614Z,1557264743.614 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 811
2019-05-07T21:32:23.617Z,1557264743.617 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-07T21:32:23.630Z,1557264743.630 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-07T21:32:23.631Z,1557264743.631 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-07T21:32:23.631Z,1557264743.631 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 812
2019-05-07T21:32:23.632Z,1557264743.632 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-07T21:32:23.633Z,1557264743.633 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-07T21:32:23.633Z,1557264743.633 [logger ThreadHandler](INFO): Protected caller Thread ID is 813
2019-05-07T21:32:23.636Z,1557264743.636 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-07T21:32:23.636Z,1557264743.636 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-07T21:32:23.640Z,1557264743.640 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-07T21:32:24.170Z,1557264744.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-07T21:32:24.172Z,1557264744.172 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-07T21:32:24.275Z,1557264744.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-07T21:32:24.277Z,1557264744.277 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-07T21:32:24.650Z,1557264744.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-07T21:32:24.652Z,1557264744.652 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-07T21:32:24.806Z,1557264744.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-07T21:32:24.808Z,1557264744.808 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-07T21:32:25.021Z,1557264745.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-07T21:32:25.023Z,1557264745.023 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-07T21:32:25.531Z,1557264745.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-07T21:32:25.533Z,1557264745.533 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-07T21:32:25.767Z,1557264745.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-07T21:32:25.768Z,1557264745.768 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-07T21:32:25.927Z,1557264745.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-07T21:32:25.929Z,1557264745.929 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-07T21:32:26.156Z,1557264746.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-07T21:32:26.157Z,1557264746.157 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-07T21:32:26.259Z,1557264746.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-07T21:32:26.261Z,1557264746.261 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-07T21:32:26.627Z,1557264746.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-07T21:32:26.629Z,1557264746.629 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-07T21:32:26.715Z,1557264746.715 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-07T21:32:27.382Z,1557264747.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-07T21:32:27.383Z,1557264747.383 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-07T21:32:28.227Z,1557264748.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-07T21:32:28.229Z,1557264748.229 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-07T21:32:28.668Z,1557264748.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-07T21:32:28.670Z,1557264748.670 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-05-07T21:32:28.674Z,1557264748.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-05-07T21:32:28.913Z,1557264748.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-05-07T21:32:29.021Z,1557264749.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-05-07T21:32:29.126Z,1557264749.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-05-07T21:32:29.368Z,1557264749.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-07T21:32:29.369Z,1557264749.369 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-05-07T21:32:29.542Z,1557264749.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-05-07T21:32:29.643Z,1557264749.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-05-07T21:32:29.746Z,1557264749.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-05-07T21:32:29.832Z,1557264749.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-05-07T21:32:29.949Z,1557264749.949 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-05-07T21:32:30.141Z,1557264750.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-05-07T21:32:30.285Z,1557264750.285 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-07T21:32:30.303Z,1557264750.303 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-07T21:32:31.152Z,1557264751.152 [AHRS_M2] Loaded
2019-05-07T21:32:31.153Z,1557264751.153 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-05-07T21:32:31.480Z,1557264751.480 [DataOverHttps] Loaded
2019-05-07T21:32:31.481Z,1557264751.481 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-07T21:32:31.482Z,1557264751.482 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4075A4E0
2019-05-07T21:32:31.482Z,1557264751.482 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 893
2019-05-07T21:32:31.496Z,1557264751.496 [Depth_Keller] Loaded
2019-05-07T21:32:31.496Z,1557264751.496 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-07T21:32:31.501Z,1557264751.501 [DropWeight] Loaded
2019-05-07T21:32:31.501Z,1557264751.501 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-05-07T21:32:31.560Z,1557264751.560 [DUSBL_Hydroid] Loaded
2019-05-07T21:32:31.560Z,1557264751.560 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-05-07T21:32:31.602Z,1557264751.602 [Micromodem] Loaded
2019-05-07T21:32:31.602Z,1557264751.602 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-05-07T21:32:31.704Z,1557264751.704 [NAL9602] Loaded
2019-05-07T21:32:31.705Z,1557264751.705 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-07T21:32:31.721Z,1557264751.721 [Onboard] Loaded
2019-05-07T21:32:31.721Z,1557264751.721 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-07T21:32:31.727Z,1557264751.727 [PowerOnly] Loaded
2019-05-07T21:32:31.728Z,1557264751.728 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-05-07T21:32:31.735Z,1557264751.735 [Radio_Surface] Loaded
2019-05-07T21:32:31.735Z,1557264751.735 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-07T21:32:31.736Z,1557264751.736 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4078A4E0
2019-05-07T21:32:31.736Z,1557264751.736 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 894
2019-05-07T21:32:31.782Z,1557264751.782 [RDI_Pathfinder] Loaded
2019-05-07T21:32:31.783Z,1557264751.783 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-05-07T21:32:33.783Z,1557264753.783 [BPC1] Loaded
2019-05-07T21:32:33.783Z,1557264753.783 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-07T21:32:33.784Z,1557264753.784 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-07T21:32:33.786Z,1557264753.786 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-07T21:32:33.809Z,1557264753.809 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-07T21:32:33.810Z,1557264753.810 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-07T21:32:33.936Z,1557264753.936 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-07T21:32:33.937Z,1557264753.937 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-07T21:32:33.958Z,1557264753.958 [NavChart] Loaded
2019-05-07T21:32:33.958Z,1557264753.958 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-07T21:32:33.963Z,1557264753.963 [UniversalFixResidualReporter] Loaded
2019-05-07T21:32:33.963Z,1557264753.963 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-07T21:32:33.963Z,1557264753.963 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-07T21:32:33.965Z,1557264753.965 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-07T21:32:34.101Z,1557264754.101 [BuoyancyServo] Loaded
2019-05-07T21:32:34.101Z,1557264754.101 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-07T21:32:34.117Z,1557264754.117 [ElevatorServo] Loaded
2019-05-07T21:32:34.117Z,1557264754.117 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-07T21:32:34.133Z,1557264754.133 [MassServo] Loaded
2019-05-07T21:32:34.133Z,1557264754.133 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-07T21:32:34.149Z,1557264754.149 [RudderServo] Loaded
2019-05-07T21:32:34.149Z,1557264754.149 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-07T21:32:34.165Z,1557264754.165 [ThrusterServo] Loaded
2019-05-07T21:32:34.165Z,1557264754.165 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-07T21:32:34.165Z,1557264754.165 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-07T21:32:34.166Z,1557264754.166 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-07T21:32:34.494Z,1557264754.494 [CTD_NeilBrown] Loaded
2019-05-07T21:32:34.494Z,1557264754.494 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-07T21:32:34.495Z,1557264754.495 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CC4E0
2019-05-07T21:32:34.496Z,1557264754.496 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 895
2019-05-07T21:32:34.542Z,1557264754.542 [WetLabsSeaOWL_UV_A] Loaded
2019-05-07T21:32:34.542Z,1557264754.542 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-05-07T21:32:34.543Z,1557264754.543 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408FC4E0
2019-05-07T21:32:34.544Z,1557264754.544 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 896
2019-05-07T21:32:34.544Z,1557264754.544 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-07T21:32:34.545Z,1557264754.545 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-07T21:32:34.894Z,1557264754.894 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-07T21:32:34.895Z,1557264754.895 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-07T21:32:34.951Z,1557264754.951 [DepthRateCalculator] Loaded
2019-05-07T21:32:34.951Z,1557264754.951 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-07T21:32:34.957Z,1557264754.957 [PitchRateCalculator] Loaded
2019-05-07T21:32:34.957Z,1557264754.957 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-07T21:32:34.971Z,1557264754.971 [SpeedCalculator] Loaded
2019-05-07T21:32:34.971Z,1557264754.971 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-07T21:32:34.993Z,1557264754.993 [TempGradientCalculator] Loaded
2019-05-07T21:32:34.993Z,1557264754.993 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-07T21:32:34.999Z,1557264754.999 [YawRateCalculator] Loaded
2019-05-07T21:32:34.999Z,1557264754.999 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-07T21:32:35.041Z,1557264755.041 [ElevatorOffsetCalculator] Loaded
2019-05-07T21:32:35.042Z,1557264755.042 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-07T21:32:35.042Z,1557264755.042 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-07T21:32:35.043Z,1557264755.043 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-07T21:32:35.209Z,1557264755.209 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-07T21:32:35.232Z,1557264755.232 [SBIT] Loaded
2019-05-07T21:32:35.232Z,1557264755.232 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-07T21:32:35.233Z,1557264755.233 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-07T21:32:35.245Z,1557264755.245 [IBIT] Loaded
2019-05-07T21:32:35.246Z,1557264755.246 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-07T21:32:35.249Z,1557264755.249 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-07T21:32:35.394Z,1557264755.394 [CBIT] Loaded
2019-05-07T21:32:35.394Z,1557264755.394 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-07T21:32:35.395Z,1557264755.395 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-07T21:32:35.395Z,1557264755.395 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-07T21:32:35.508Z,1557264755.508 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-07T21:32:35.509Z,1557264755.509 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-07T21:32:35.638Z,1557264755.638 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-07T21:32:35.638Z,1557264755.638 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-07T21:32:35.727Z,1557264755.727 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-07T21:32:35.815Z,1557264755.815 [VerticalControl] Loaded
2019-05-07T21:32:35.816Z,1557264755.816 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-07T21:32:35.816Z,1557264755.816 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-07T21:32:35.877Z,1557264755.877 [HorizontalControl] Loaded
2019-05-07T21:32:35.877Z,1557264755.877 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-07T21:32:35.878Z,1557264755.878 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-07T21:32:35.880Z,1557264755.880 [SpeedControl] Loaded
2019-05-07T21:32:35.880Z,1557264755.880 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-07T21:32:35.881Z,1557264755.881 [LoopControl](DEBUG): Construct LoopControl.
2019-05-07T21:32:35.881Z,1557264755.881 [LoopControl] Loaded
2019-05-07T21:32:35.882Z,1557264755.882 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-07T21:32:35.882Z,1557264755.882 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-07T21:32:35.883Z,1557264755.883 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-07T21:32:35.924Z,1557264755.924 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-07T21:32:35.928Z,1557264755.928 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-07T21:32:35.929Z,1557264755.929 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-07T21:32:35.936Z,1557264755.936 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-07T21:32:35.937Z,1557264755.937 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0
2019-05-07T21:32:35.938Z,1557264755.938 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 897
2019-05-07T21:32:35.943Z,1557264755.943 [Supervisor](INFO): Main Thread ID is 805
2019-05-07T21:32:35.943Z,1557264755.943 [Supervisor](DEBUG): Running supervisor.
2019-05-07T21:32:35.944Z,1557264755.944 [CommandLine ThreadHandler](INFO): Handler Thread ID is 898
2019-05-07T21:32:35.946Z,1557264755.946 [controlThread ThreadHandler](INFO): Handler Thread ID is 899
2019-05-07T21:32:35.946Z,1557264755.946 [controlThread](DEBUG): Initializing ControlThread
2019-05-07T21:32:35.954Z,1557264755.954 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-07T21:32:35.955Z,1557264755.955 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-07T21:32:35.956Z,1557264755.956 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-07T21:32:35.956Z,1557264755.956 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-07T21:32:35.956Z,1557264755.956 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-07T21:32:35.956Z,1557264755.956 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-07T21:32:35.957Z,1557264755.957 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-07T21:32:35.957Z,1557264755.957 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-07T21:32:35.958Z,1557264755.958 [SBIT](INFO): Initialize SBIT Component.
2019-05-07T21:32:35.958Z,1557264755.958 [SBIT](IMPORTANT): git: 2019-04-10-23-g672f59b
2019-05-07T21:32:35.958Z,1557264755.958 [SBIT](INFO): git hash: 672f59b3bb9ba34f4915fd4dcb9119316785292f
2019-05-07T21:32:35.959Z,1557264755.959 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-07T21:32:35.960Z,1557264755.960 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-07T21:32:35.961Z,1557264755.961 [SBIT](INFO): Beginning SBIT in 44.000000 seconds.
2019-05-07T21:32:35.962Z,1557264755.962 [IBIT](INFO): Initialize IBIT Component.
2019-05-07T21:32:35.963Z,1557264755.963 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-07T21:32:35.963Z,1557264755.963 [logger ThreadHandler](INFO): Handler Thread ID is 900
2019-05-07T21:32:35.974Z,1557264755.974 [CBIT](DEBUG): Initialized mux pins.
2019-05-07T21:32:35.974Z,1557264755.974 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-07T21:32:35.983Z,1557264755.983 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 901
2019-05-07T21:32:35.984Z,1557264755.984 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-07T21:32:35.998Z,1557264755.998 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-07T21:32:35.998Z,1557264755.998 [CBIT](DEBUG): Initializing heartbeat.
2019-05-07T21:32:36.036Z,1557264756.036 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 902
2019-05-07T21:32:36.043Z,1557264756.043 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 903
2019-05-07T21:32:36.044Z,1557264756.044 [CTD_NeilBrown](INFO): Powering down
2019-05-07T21:32:36.070Z,1557264756.070 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-07T21:32:36.070Z,1557264756.070 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-07T21:32:36.083Z,1557264756.083 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 904
2019-05-07T21:32:36.083Z,1557264756.083 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-05-07T21:32:36.103Z,1557264756.103 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 905
2019-05-07T21:32:36.106Z,1557264756.106 [CBIT](DEBUG): Backplane powered.
2019-05-07T21:32:36.107Z,1557264756.107 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-07T21:32:36.109Z,1557264756.109 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-07T21:32:36.109Z,1557264756.109 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-07T21:32:36.110Z,1557264756.110 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-07T21:32:36.111Z,1557264756.111 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-07T21:32:36.140Z,1557264756.140 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-07T21:32:36.152Z,1557264756.152 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-07T21:32:36.153Z,1557264756.153 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-07T21:32:36.153Z,1557264756.153 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-07T21:32:36.153Z,1557264756.153 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-07T21:32:36.153Z,1557264756.153 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-07T21:32:36.153Z,1557264756.153 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-07T21:32:36.154Z,1557264756.154 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-07T21:32:36.154Z,1557264756.154 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-07T21:32:36.154Z,1557264756.154 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-07T21:32:36.154Z,1557264756.154 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-07T21:32:36.154Z,1557264756.154 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-07T21:32:36.155Z,1557264756.155 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-07T21:32:36.155Z,1557264756.155 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-07T21:32:36.155Z,1557264756.155 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-07T21:32:36.155Z,1557264756.155 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-07T21:32:36.155Z,1557264756.155 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-07T21:32:36.173Z,1557264756.173 [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-05-07T21:32:36.174Z,1557264756.174 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-07T21:32:36.268Z,1557264756.268 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-07T21:32:36.269Z,1557264756.269 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-07T21:32:36.271Z,1557264756.271 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-07T21:32:36.282Z,1557264756.282 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-07T21:32:36.308Z,1557264756.308 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-07T21:32:36.314Z,1557264756.314 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-07T21:32:36.333Z,1557264756.333 [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-05-07T21:32:36.338Z,1557264756.338 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-07T21:32:36.371Z,1557264756.371 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-07T21:32:36.438Z,1557264756.438 [Radio_Surface](INFO): Powering up
2019-05-07T21:32:36.504Z,1557264756.504 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-05-07T21:32:36.505Z,1557264756.505 [DUSBL_Hydroid](INFO): Powering up
2019-05-07T21:32:36.505Z,1557264756.505 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-05-07T21:32:36.579Z,1557264756.579 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-07T21:32:36.652Z,1557264756.652 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-07T21:32:36.659Z,1557264756.659 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-07T21:32:36.660Z,1557264756.660 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-07T21:32:36.667Z,1557264756.667 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-07T21:32:36.668Z,1557264756.668 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-07T21:32:36.675Z,1557264756.675 [MassServo](DEBUG): Initializing MassServo.
2019-05-07T21:32:36.675Z,1557264756.675 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-07T21:32:36.683Z,1557264756.683 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-07T21:32:36.683Z,1557264756.683 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-07T21:32:36.691Z,1557264756.691 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-07T21:32:36.764Z,1557264756.764 [Micromodem](INFO): Powering up
2019-05-07T21:32:36.764Z,1557264756.764 [Micromodem](DEBUG): Initializing Micromodem.
2019-05-07T21:32:36.803Z,1557264756.803 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-07T21:32:50.116Z,1557264770.116 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-05-07T21:32:54.524Z,1557264774.524 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-05-07T21:33:01.819Z,1557264781.819 [NAL9602](INFO): Powering up NAL9602
2019-05-07T21:33:12.708Z,1557264792.708 [NAL9602](INFO): NAL9602 initialized
2019-05-07T21:33:13.534Z,1557264793.534 [NAL9602](DEBUG): Fix Requested
2019-05-07T21:33:20.436Z,1557264800.436 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-07T21:33:20.452Z,1557264800.452 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-07T21:33:31.455Z,1557264811.455 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.023123
CHAN A1 (24V): -0.026609
CHAN A2 (12V): -0.006856
CHAN A3 (5V): -0.002145
CHAN B0 (3.3V): 0.000126
CHAN B1 (3.15aV): 0.000251
CHAN B2 (3.15bV): 0.000286
CHAN B3 (GND): 0.002030
OPEN: 0.006460
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-07T21:33:47.639Z,1557264827.639 [NAL9602](INFO): SBD MO Status=2, MOMSN=3873, MT Status=2, MTMSN=0
2019-05-07T21:33:47.639Z,1557264827.639 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-05-07T21:34:00.565Z,1557264840.565 [CommandLine](IMPORTANT): got command restart application
2019-05-07T21:34:01.570Z,1557264841.570 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-05-07T21:34:01.571Z,1557264841.571 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T21:34:01.571Z,1557264841.571 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:01.618Z,1557264841.618 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-07T21:34:01.618Z,1557264841.618 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:01.619Z,1557264841.619 [CommandLine](INFO): Join timeout helper Thread ID is 926
2019-05-07T21:34:01.630Z,1557264841.630 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-07T21:34:01.630Z,1557264841.630 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:01.631Z,1557264841.631 [NavChartDb](INFO): Join timeout helper Thread ID is 927
2019-05-07T21:34:02.002Z,1557264842.002 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T21:34:02.003Z,1557264842.003 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.022Z,1557264842.022 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-05-07T21:34:02.022Z,1557264842.022 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.023Z,1557264842.023 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 928
2019-05-07T21:34:02.178Z,1557264842.178 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T21:34:02.179Z,1557264842.179 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-05-07T21:34:02.179Z,1557264842.179 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.182Z,1557264842.182 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-07T21:34:02.183Z,1557264842.183 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.183Z,1557264842.183 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 929
2019-05-07T21:34:02.195Z,1557264842.195 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T21:34:02.195Z,1557264842.195 [CTD_NeilBrown](INFO): Powering down
2019-05-07T21:34:02.202Z,1557264842.202 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-05-07T21:34:02.202Z,1557264842.202 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19050714413811,35.0, -0.1,   09,  0
2019-05-07T21:34:02.206Z,1557264842.206 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.222Z,1557264842.222 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-07T21:34:02.222Z,1557264842.222 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.223Z,1557264842.223 [Radio_Surface](INFO): Join timeout helper Thread ID is 930
2019-05-07T21:34:02.522Z,1557264842.522 [Radio_Surface](INFO): Powering down
2019-05-07T21:34:02.523Z,1557264842.523 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T21:34:02.524Z,1557264842.524 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.543Z,1557264842.543 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-07T21:34:02.543Z,1557264842.543 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.544Z,1557264842.544 [DataOverHttps](INFO): Join timeout helper Thread ID is 931
2019-05-07T21:34:02.859Z,1557264842.859 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T21:34:02.859Z,1557264842.859 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.871Z,1557264842.871 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-07T21:34:02.871Z,1557264842.871 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.871Z,1557264842.871 [logger](INFO): Join timeout helper Thread ID is 932
2019-05-07T21:34:02.971Z,1557264842.971 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T21:34:02.971Z,1557264842.971 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.991Z,1557264842.991 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-07T21:34:02.992Z,1557264842.992 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.992Z,1557264842.992 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-07T21:34:02.992Z,1557264842.992 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:02.992Z,1557264842.992 [controlThread](INFO): Join timeout helper Thread ID is 933
2019-05-07T21:34:03.060Z,1557264843.060 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T21:34:03.060Z,1557264843.060 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-07T21:34:03.061Z,1557264843.061 [AHRS_M2](INFO): Powering down
2019-05-07T21:34:03.131Z,1557264843.131 [DUSBL_Hydroid](INFO): Powering down
2019-05-07T21:34:03.223Z,1557264843.223 [Micromodem](INFO): Powering down
2019-05-07T21:34:03.319Z,1557264843.319 [NAL9602](INFO): Powering down
2019-05-07T21:34:03.391Z,1557264843.391 [RDI_Pathfinder](INFO): Powering down
2019-05-07T21:34:03.392Z,1557264843.392 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-07T21:34:03.393Z,1557264843.393 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-07T21:34:03.393Z,1557264843.393 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-07T21:34:03.394Z,1557264843.394 [MissionManager](INFO): Uninitializing Mission Default
2019-05-07T21:34:03.396Z,1557264843.396 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-07T21:34:03.397Z,1557264843.397 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-07T21:34:03.398Z,1557264843.398 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-07T21:34:03.398Z,1557264843.398 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-07T21:34:03.398Z,1557264843.398 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-07T21:34:03.399Z,1557264843.399 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-07T21:34:03.399Z,1557264843.399 [BuoyancyServo](INFO): Powering down
2019-05-07T21:34:03.411Z,1557264843.411 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-07T21:34:03.411Z,1557264843.411 [ElevatorServo](INFO): Powering down
2019-05-07T21:34:03.412Z,1557264843.412 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-07T21:34:03.412Z,1557264843.412 [MassServo](INFO): Powering down
2019-05-07T21:34:03.412Z,1557264843.412 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-07T21:34:03.412Z,1557264843.412 [RudderServo](INFO): Powering down
2019-05-07T21:34:03.413Z,1557264843.413 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-07T21:34:03.413Z,1557264843.413 [ThrusterServo](INFO): Powering down
2019-05-07T21:34:03.414Z,1557264843.414 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-07T21:34:03.415Z,1557264843.415 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-07T21:34:03.415Z,1557264843.415 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-07T21:34:03.415Z,1557264843.415 [CBIT](DEBUG): Powering off loads.
2019-05-07T21:34:03.426Z,1557264843.426 [CBIT](DEBUG): Disabling WDT.
2019-05-07T21:34:03.438Z,1557264843.438 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-07T21:34:03.439Z,1557264843.439 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:03.490Z,1557264843.490 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:03.499Z,1557264843.499 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:03.896Z,1557264843.896 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:03.898Z,1557264843.898 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:03.954Z,1557264843.954 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-07T21:34:04.013Z,1557264844.013 [logger ThreadHandler](INFO): Thread cancelled.