2018-09-22T04:18:06.027Z,1537589886.027 [Supervisor](DEBUG): Initializing supervisor.
2018-09-22T04:18:06.030Z,1537589886.030 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-22T04:18:06.030Z,1537589886.030 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-09-22T04:18:06.031Z,1537589886.031 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-22T04:18:06.032Z,1537589886.032 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-22T04:18:06.032Z,1537589886.032 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-09-22T04:18:06.035Z,1537589886.035 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-22T04:18:06.046Z,1537589886.046 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-22T04:18:06.047Z,1537589886.047 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-22T04:18:06.048Z,1537589886.048 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-09-22T04:18:06.048Z,1537589886.048 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-22T04:18:06.049Z,1537589886.049 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-22T04:18:06.050Z,1537589886.050 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-09-22T04:18:06.052Z,1537589886.052 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-22T04:18:06.052Z,1537589886.052 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-22T04:18:06.056Z,1537589886.056 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-22T04:18:06.245Z,1537589886.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-22T04:18:06.246Z,1537589886.246 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-22T04:18:06.390Z,1537589886.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-22T04:18:06.391Z,1537589886.391 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-22T04:18:06.737Z,1537589886.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-22T04:18:06.739Z,1537589886.739 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-22T04:18:06.880Z,1537589886.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-22T04:18:06.882Z,1537589886.882 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-22T04:18:06.963Z,1537589886.963 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-22T04:18:07.165Z,1537589887.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-22T04:18:07.167Z,1537589887.167 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-22T04:18:07.266Z,1537589887.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-22T04:18:07.266Z,1537589887.266 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-22T04:18:07.612Z,1537589887.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-22T04:18:07.614Z,1537589887.614 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-22T04:18:08.070Z,1537589888.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-22T04:18:08.071Z,1537589888.071 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-22T04:18:08.399Z,1537589888.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-22T04:18:08.400Z,1537589888.400 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-22T04:18:08.902Z,1537589888.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-22T04:18:08.903Z,1537589888.903 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-22T04:18:09.111Z,1537589889.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-22T04:18:09.112Z,1537589889.112 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-22T04:18:09.211Z,1537589889.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-22T04:18:09.211Z,1537589889.211 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-22T04:18:09.817Z,1537589889.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-22T04:18:10.972Z,1537589890.972 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-22T04:18:11.134Z,1537589891.134 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-22T04:18:11.136Z,1537589891.136 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-09-22T04:18:11.142Z,1537589891.142 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-09-22T04:18:11.371Z,1537589891.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-22T04:18:11.371Z,1537589891.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-09-22T04:18:11.471Z,1537589891.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-09-22T04:18:11.565Z,1537589891.565 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-09-22T04:18:11.669Z,1537589891.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-09-22T04:18:11.755Z,1537589891.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-09-22T04:18:11.890Z,1537589891.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-09-22T04:18:12.059Z,1537589892.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-09-22T04:18:12.170Z,1537589892.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-09-22T04:18:12.256Z,1537589892.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-09-22T04:18:12.340Z,1537589892.340 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-09-22T04:18:12.532Z,1537589892.532 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-09-22T04:18:12.538Z,1537589892.538 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-22T04:18:12.692Z,1537589892.692 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-22T04:18:12.722Z,1537589892.722 [SBIT] Loaded
2018-09-22T04:18:12.722Z,1537589892.722 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-22T04:18:12.723Z,1537589892.723 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-22T04:18:12.750Z,1537589892.750 [IBIT] Loaded
2018-09-22T04:18:12.751Z,1537589892.751 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-22T04:18:12.754Z,1537589892.754 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-22T04:18:12.909Z,1537589892.909 [CBIT] Loaded
2018-09-22T04:18:12.910Z,1537589892.910 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-22T04:18:12.910Z,1537589892.910 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-22T04:18:12.911Z,1537589892.911 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-22T04:18:12.000Z,1537589893.000 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-22T04:18:13.100Z,1537589893.100 [VerticalControl] Loaded
2018-09-22T04:18:13.100Z,1537589893.100 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-22T04:18:13.101Z,1537589893.101 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-22T04:18:13.162Z,1537589893.162 [HorizontalControl] Loaded
2018-09-22T04:18:13.162Z,1537589893.162 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-22T04:18:13.163Z,1537589893.163 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-22T04:18:13.168Z,1537589893.168 [SpeedControl] Loaded
2018-09-22T04:18:13.168Z,1537589893.168 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-22T04:18:13.169Z,1537589893.169 [LoopControl](DEBUG): Construct LoopControl.
2018-09-22T04:18:13.170Z,1537589893.170 [LoopControl] Loaded
2018-09-22T04:18:13.170Z,1537589893.170 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-22T04:18:13.170Z,1537589893.170 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-22T04:18:13.171Z,1537589893.171 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-22T04:18:13.228Z,1537589893.228 [DepthRateCalculator] Loaded
2018-09-22T04:18:13.228Z,1537589893.228 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-22T04:18:13.233Z,1537589893.233 [PitchRateCalculator] Loaded
2018-09-22T04:18:13.234Z,1537589893.234 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-22T04:18:13.249Z,1537589893.249 [SpeedCalculator] Loaded
2018-09-22T04:18:13.250Z,1537589893.250 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-22T04:18:13.271Z,1537589893.271 [TempGradientCalculator] Loaded
2018-09-22T04:18:13.271Z,1537589893.271 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-22T04:18:13.276Z,1537589893.276 [YawRateCalculator] Loaded
2018-09-22T04:18:13.276Z,1537589893.276 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-22T04:18:13.306Z,1537589893.306 [ElevatorOffsetCalculator] Loaded
2018-09-22T04:18:13.306Z,1537589893.306 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-22T04:18:13.307Z,1537589893.307 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-22T04:18:13.307Z,1537589893.307 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-22T04:18:13.412Z,1537589893.412 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-22T04:18:13.412Z,1537589893.412 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-22T04:18:13.697Z,1537589893.697 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-22T04:18:13.697Z,1537589893.697 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-22T04:18:13.812Z,1537589893.812 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-09-22T04:18:13.812Z,1537589893.812 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-09-22T04:18:13.866Z,1537589893.866 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-22T04:18:13.867Z,1537589893.867 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-22T04:18:13.883Z,1537589893.883 [NavChart] Loaded
2018-09-22T04:18:13.883Z,1537589893.883 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-22T04:18:13.887Z,1537589893.887 [UniversalFixResidualReporter] Loaded
2018-09-22T04:18:13.888Z,1537589893.888 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-22T04:18:13.888Z,1537589893.888 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-22T04:18:13.889Z,1537589893.889 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-22T04:18:13.914Z,1537589893.914 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-22T04:18:13.914Z,1537589893.914 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-22T04:18:14.132Z,1537589894.132 [Aanderaa_O2] Loaded
2018-09-22T04:18:14.132Z,1537589894.132 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-09-22T04:18:14.178Z,1537589894.178 [WetLabsSeaOWL_UV_A] Loaded
2018-09-22T04:18:14.178Z,1537589894.178 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-09-22T04:18:14.179Z,1537589894.179 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406DB4E0
2018-09-22T04:18:14.180Z,1537589894.180 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 881
2018-09-22T04:18:14.180Z,1537589894.180 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-22T04:18:14.181Z,1537589894.181 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-22T04:18:14.804Z,1537589894.804 [AHRS_M2] Loaded
2018-09-22T04:18:14.805Z,1537589894.805 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-09-22T04:18:14.940Z,1537589894.940 [DataOverHttps] Loaded
2018-09-22T04:18:14.940Z,1537589894.940 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-22T04:18:14.954Z,1537589894.954 [Depth_Keller] Loaded
2018-09-22T04:18:14.954Z,1537589894.954 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-22T04:18:14.959Z,1537589894.959 [DropWeight] Loaded
2018-09-22T04:18:14.959Z,1537589894.959 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-22T04:18:15.051Z,1537589895.051 [NAL9602] Loaded
2018-09-22T04:18:15.051Z,1537589895.051 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-22T04:18:15.057Z,1537589895.057 [Onboard] Loaded
2018-09-22T04:18:15.057Z,1537589895.057 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-22T04:18:15.064Z,1537589895.064 [Radio_Surface] Loaded
2018-09-22T04:18:15.064Z,1537589895.064 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-22T04:18:15.065Z,1537589895.065 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4093C4E0
2018-09-22T04:18:15.066Z,1537589895.066 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-09-22T04:18:15.094Z,1537589895.094 [RDI_Pathfinder] Loaded
2018-09-22T04:18:15.095Z,1537589895.095 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-09-22T04:18:15.117Z,1537589895.117 [RDI_PathfinderUp] Loaded
2018-09-22T04:18:15.117Z,1537589895.117 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2018-09-22T04:18:15.123Z,1537589895.123 [SCPI] Loaded
2018-09-22T04:18:15.123Z,1537589895.123 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-09-22T04:18:17.081Z,1537589897.081 [BPC1] Loaded
2018-09-22T04:18:17.082Z,1537589897.082 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-22T04:18:17.082Z,1537589897.082 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-22T04:18:17.083Z,1537589897.083 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-22T04:18:17.203Z,1537589897.203 [BuoyancyServo] Loaded
2018-09-22T04:18:17.204Z,1537589897.204 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-22T04:18:17.216Z,1537589897.216 [ElevatorServo] Loaded
2018-09-22T04:18:17.216Z,1537589897.216 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-09-22T04:18:17.227Z,1537589897.227 [MassServo] Loaded
2018-09-22T04:18:17.227Z,1537589897.227 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-09-22T04:18:17.239Z,1537589897.239 [RudderServo] Loaded
2018-09-22T04:18:17.239Z,1537589897.239 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-09-22T04:18:17.250Z,1537589897.250 [ThrusterServo] Loaded
2018-09-22T04:18:17.250Z,1537589897.250 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-09-22T04:18:17.250Z,1537589897.250 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-22T04:18:17.251Z,1537589897.251 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-22T04:18:17.379Z,1537589897.379 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-22T04:18:17.380Z,1537589897.380 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-22T04:18:17.417Z,1537589897.417 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-22T04:18:17.421Z,1537589897.421 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-22T04:18:17.422Z,1537589897.422 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-22T04:18:17.428Z,1537589897.428 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-22T04:18:17.429Z,1537589897.429 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0
2018-09-22T04:18:17.430Z,1537589897.430 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 883
2018-09-22T04:18:17.434Z,1537589897.434 [Supervisor](INFO): Main Thread ID is 795
2018-09-22T04:18:17.434Z,1537589897.434 [Supervisor](DEBUG): Running supervisor.
2018-09-22T04:18:17.435Z,1537589897.435 [CommandLine ThreadHandler](INFO): Handler Thread ID is 884
2018-09-22T04:18:17.438Z,1537589897.438 [controlThread ThreadHandler](INFO): Handler Thread ID is 885
2018-09-22T04:18:17.438Z,1537589897.438 [controlThread](DEBUG): Initializing ControlThread
2018-09-22T04:18:17.439Z,1537589897.439 [SBIT](INFO): Initialize SBIT Component.
2018-09-22T04:18:17.439Z,1537589897.439 [SBIT](IMPORTANT): git: 2018-09-20
2018-09-22T04:18:17.440Z,1537589897.440 [SBIT](INFO): git hash: cc82e4f04ca47b19cdcc10b5d3e1760510b3ba6f
2018-09-22T04:18:17.440Z,1537589897.440 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-22T04:18:17.440Z,1537589897.440 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-22T04:18:17.442Z,1537589897.442 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-09-22T04:18:17.442Z,1537589897.442 [IBIT](INFO): Initialize IBIT Component.
2018-09-22T04:18:17.443Z,1537589897.443 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-22T04:18:17.443Z,1537589897.443 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-09-22T04:18:17.443Z,1537589897.443 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-09-22T04:18:17.444Z,1537589897.444 [logger ThreadHandler](INFO): Handler Thread ID is 886
2018-09-22T04:18:17.461Z,1537589897.461 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 887
2018-09-22T04:18:17.463Z,1537589897.463 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-22T04:18:17.490Z,1537589897.490 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 888
2018-09-22T04:18:17.496Z,1537589897.496 [Radio_Surface](INFO): Powering up
2018-09-22T04:18:17.513Z,1537589897.513 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 889
2018-09-22T04:18:17.522Z,1537589897.522 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-22T04:18:17.522Z,1537589897.522 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-22T04:18:17.522Z,1537589897.522 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-22T04:18:17.523Z,1537589897.523 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-22T04:18:17.523Z,1537589897.523 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-22T04:18:17.523Z,1537589897.523 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-22T04:18:17.523Z,1537589897.523 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-22T04:18:17.523Z,1537589897.523 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-22T04:18:17.524Z,1537589897.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-22T04:18:17.524Z,1537589897.524 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-22T04:18:17.524Z,1537589897.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-22T04:18:17.524Z,1537589897.524 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-22T04:18:17.524Z,1537589897.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-22T04:18:17.524Z,1537589897.524 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-22T04:18:17.525Z,1537589897.525 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-22T04:18:17.525Z,1537589897.525 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-22T04:18:17.549Z,1537589897.549 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-22T04:18:17.551Z,1537589897.551 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-22T04:18:17.552Z,1537589897.552 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-22T04:18:17.552Z,1537589897.552 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-22T04:18:17.552Z,1537589897.552 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-22T04:18:17.553Z,1537589897.553 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-22T04:18:17.553Z,1537589897.553 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-22T04:18:17.553Z,1537589897.553 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-22T04:18:17.554Z,1537589897.554 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-22T04:18:17.554Z,1537589897.554 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-22T04:18:17.555Z,1537589897.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-22T04:18:17.556Z,1537589897.556 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-22T04:18:17.556Z,1537589897.556 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-22T04:18:17.557Z,1537589897.557 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-22T04:18:17.557Z,1537589897.557 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-22T04:18:17.558Z,1537589897.558 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-22T04:18:17.558Z,1537589897.558 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-22T04:18:17.558Z,1537589897.558 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-22T04:18:17.574Z,1537589897.574 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-22T04:18:17.641Z,1537589897.641 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-22T04:18:17.668Z,1537589897.668 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-09-22T04:18:17.669Z,1537589897.669 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-22T04:18:17.751Z,1537589897.751 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-22T04:18:17.753Z,1537589897.753 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-22T04:18:17.754Z,1537589897.754 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-22T04:18:17.784Z,1537589897.784 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-22T04:18:17.787Z,1537589897.787 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-22T04:18:17.812Z,1537589897.812 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-22T04:18:17.816Z,1537589897.816 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-09-22T04:18:17.825Z,1537589897.825 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-22T04:18:17.875Z,1537589897.875 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-09-22T04:18:18.006Z,1537589898.006 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-22T04:18:18.027Z,1537589898.027 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-09-22T04:18:18.205Z,1537589898.205 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-22T04:18:18.297Z,1537589898.297 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:18.349Z,1537589898.349 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:18.464Z,1537589898.464 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-22T04:18:18.477Z,1537589898.477 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-22T04:18:18.499Z,1537589898.499 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-09-22T04:18:18.505Z,1537589898.505 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-09-22T04:18:18.527Z,1537589898.527 [MassServo](DEBUG): Initializing EZServoServo.
2018-09-22T04:18:18.533Z,1537589898.533 [MassServo](DEBUG): Initializing MassServo.
2018-09-22T04:18:18.547Z,1537589898.547 [RudderServo](DEBUG): Initializing EZServoServo.
2018-09-22T04:18:18.554Z,1537589898.554 [RudderServo](DEBUG): Initializing RudderServo.
2018-09-22T04:18:18.567Z,1537589898.567 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-22T04:18:18.573Z,1537589898.573 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-22T04:18:18.841Z,1537589898.841 [RDI_PathfinderUp](INFO): Powering down
2018-09-22T04:18:18.897Z,1537589898.897 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:18.898Z,1537589898.898 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:19.066Z,1537589899.066 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-09-22T04:18:19.092Z,1537589899.092 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:19.093Z,1537589899.093 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:19.437Z,1537589899.437 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-22T04:18:19.438Z,1537589899.438 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-22T04:18:19.438Z,1537589899.438 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-22T04:18:19.438Z,1537589899.438 [BuoyancyServo](ERROR): Communications Fault
2018-09-22T04:18:19.653Z,1537589899.653 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-22T04:18:19.724Z,1537589899.724 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:19.733Z,1537589899.733 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:19.747Z,1537589899.747 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-22T04:18:19.748Z,1537589899.748 [BuoyancyServo](INFO): Powering down
2018-09-22T04:18:20.089Z,1537589900.089 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:20.090Z,1537589900.090 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:20.488Z,1537589900.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:20.489Z,1537589900.489 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:20.888Z,1537589900.888 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:20.889Z,1537589900.889 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:21.328Z,1537589901.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:21.329Z,1537589901.329 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-22T04:18:22.273Z,1537589902.273 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-22T04:18:22.273Z,1537589902.273 [BuoyancyServo] No Fault, FailCount= 1
2018-09-22T04:18:22.549Z,1537589902.549 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-22T04:18:22.678Z,1537589902.678 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-22T04:18:27.879Z,1537589907.879 [Aanderaa_O2](FAULT): Timed out starting
2018-09-22T04:18:27.879Z,1537589907.879 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-09-22T04:18:27.879Z,1537589907.879 [Aanderaa_O2](ERROR): Communications Fault
2018-09-22T04:18:27.952Z,1537589907.952 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-09-22T04:18:28.397Z,1537589908.397 [Aanderaa_O2](INFO): Powering down
2018-09-22T04:18:29.123Z,1537589909.123 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-09-22T04:18:29.123Z,1537589909.123 [Aanderaa_O2] No Fault, FailCount= 1
2018-09-22T04:18:31.514Z,1537589911.514 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-22T04:18:39.879Z,1537589919.879 [Aanderaa_O2](FAULT): Timed out starting
2018-09-22T04:18:39.879Z,1537589919.879 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-09-22T04:18:39.879Z,1537589919.879 [Aanderaa_O2](ERROR): Communications Fault
2018-09-22T04:18:39.926Z,1537589919.926 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-09-22T04:18:40.397Z,1537589920.397 [Aanderaa_O2](INFO): Powering down
2018-09-22T04:18:41.141Z,1537589921.141 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-09-22T04:18:41.141Z,1537589921.141 [Aanderaa_O2] No Fault, FailCount= 2
2018-09-22T04:18:45.087Z,1537589925.087 [NAL9602](INFO): Powering up NAL9602
2018-09-22T04:18:45.937Z,1537589925.937 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-22T04:18:45.942Z,1537589925.942 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-22T04:18:51.603Z,1537589931.603 [Aanderaa_O2](FAULT): Timed out starting
2018-09-22T04:18:51.603Z,1537589931.603 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-09-22T04:18:51.603Z,1537589931.603 [Aanderaa_O2](ERROR): Communications Fault
2018-09-22T04:18:51.754Z,1537589931.754 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-09-22T04:18:51.755Z,1537589931.755 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-09-22T04:18:52.121Z,1537589932.121 [Aanderaa_O2](INFO): Powering down
2018-09-22T04:18:56.011Z,1537589936.011 [NAL9602](INFO): NAL9602 initialized
2018-09-22T04:18:56.938Z,1537589936.938 [CBIT](IMPORTANT): Low side ground fault detected
mA:
CHAN A0 (Batt): 0.875859
CHAN A1 (24V): 0.874045
CHAN A2 (12V): 0.907496
CHAN A3 (5V): 0.081012
CHAN B0 (3.3V): 0.057403
CHAN B1 (3.15aV): 0.049304
CHAN B2 (3.15bV): 0.049904
CHAN B3 (GND): -0.038127
OPEN: 0.012743
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-22T04:19:15.012Z,1537589955.012 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=1012, MT Status=1, MTMSN=41
2018-09-22T04:19:15.012Z,1537589955.012 [NAL9602](INFO): Data available in MT queue
2018-09-22T04:19:15.654Z,1537589955.654 [NAL9602](INFO): Received command:! grep -ni avg_current /mnt/mmc/LRAUV/Logs/latest/syslog
2018-09-22T04:19:15.662Z,1537589955.662 [CommandLine](IMPORTANT): got command ! grep -ni avg_current /mnt/mmc/LRAUV/Logs/latest/syslog
2018-09-22T04:19:40.031Z,1537589980.031 [SBIT](IMPORTANT): SBIT PASSED
2018-09-22T04:19:40.090Z,1537589980.090 [CommandLine](IMPORTANT): got command configSet list
2018-09-22T04:19:40.090Z,1537589980.090 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-22T04:19:40.094Z,1537589980.094 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-09-22T04:19:40.094Z,1537589980.094 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 hour;

2018-09-22T04:19:40.094Z,1537589980.094 [CommandLine](IMPORTANT): BuoyancyServo.fastPumpCoefficient=1 none;

2018-09-22T04:19:40.094Z,1537589980.094 [CommandLine](IMPORTANT): BuoyancyServo.velocity=600 none;

2018-09-22T04:19:40.094Z,1537589980.094 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_chlorophyll_in_sea_water 0.500000 microgram_per_liter;

2018-09-22T04:19:40.095Z,1537589980.095 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-09-22T04:19:40.095Z,1537589980.095 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold=13.7 volt;

2018-09-22T04:19:40.095Z,1537589980.095 [CommandLine](IMPORTANT): RDI_PathfinderUp.loadAtStartup=0 bool;

2018-09-22T04:19:40.095Z,1537589980.095 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=205 cubic_centimeter;

2018-09-22T04:19:40.095Z,1537589980.095 [CommandLine](IMPORTANT): VerticalControl.massDefault=0 millimeter;

2018-09-22T04:19:40.422Z,1537589980.422 [MissionManager](IMPORTANT): Started mission Startup
2018-09-22T04:19:40.423Z,1537589980.423 [Startup] Running Loop=1
2018-09-22T04:19:40.423Z,1537589980.423 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-22T04:19:40.423Z,1537589980.423 [Startup:A.GoToSurface] Running Loop=1
2018-09-22T04:19:40.423Z,1537589980.423 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-22T04:19:40.425Z,1537589980.425 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-22T04:19:40.426Z,1537589980.426 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-22T04:19:40.427Z,1537589980.427 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-22T04:19:40.428Z,1537589980.428 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-22T04:19:40.429Z,1537589980.429 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-22T04:19:40.453Z,1537589980.453 [Startup:StartupSatComms] Running Loop=1
2018-09-22T04:19:40.453Z,1537589980.453 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-22T04:19:40.454Z,1537589980.454 [Startup:StartupSatComms:A] Running Loop=1
2018-09-22T04:19:40.842Z,1537589980.842 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-22T04:20:40.839Z,1537590040.839 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-22T04:19:40.5Z
2018-09-22T04:20:40.839Z,1537590040.839 [Startup:StartupSatComms:A] Stopped
2018-09-22T04:20:40.839Z,1537590040.839 [Startup:StartupSatComms:B] Running Loop=1
2018-09-22T04:20:41.282Z,1537590041.282 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-22T04:20:54.060Z,1537590054.060 [NAL9602](INFO): SBD MO Status=1, MOMSN=1013, MT Status=0, MTMSN=0
2018-09-22T04:20:54.113Z,1537590054.113 [NAL9602](INFO): Sent 332 bytes from file Logs/20180921T222158/Courier0061.lzma
2018-09-22T04:20:54.113Z,1537590054.113 [NAL9602](INFO): Packets left to send: 2
2018-09-22T04:20:54.115Z,1537590054.115 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180921T222158/Courier0061.lzma.parts/0002.sbd
2018-09-22T04:21:06.152Z,1537590066.152 [NAL9602](INFO): SBD MO Status=1, MOMSN=1014, MT Status=0, MTMSN=0
2018-09-22T04:21:06.201Z,1537590066.201 [NAL9602](INFO): Sent 332 bytes from file Logs/20180921T222158/Courier0061.lzma
2018-09-22T04:21:06.201Z,1537590066.201 [NAL9602](INFO): Packets left to send: 1
2018-09-22T04:21:06.203Z,1537590066.203 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180921T222158/Courier0061.lzma.parts/0001.sbd
2018-09-22T04:21:14.704Z,1537590074.704 [NAL9602](INFO): SBD MO Status=1, MOMSN=1015, MT Status=0, MTMSN=0
2018-09-22T04:21:14.757Z,1537590074.757 [NAL9602](INFO): Sent 246 bytes from file Logs/20180921T222158/Courier0061.lzma
2018-09-22T04:21:14.757Z,1537590074.757 [NAL9602](INFO): Packets left to send: 0
2018-09-22T04:21:14.758Z,1537590074.758 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180921T222158/Courier0061.lzma.parts/0000.sbd
2018-09-22T04:21:14.759Z,1537590074.759 [NAL9602](DEBUG): Completed sending Logs/20180921T222158/Courier0061.lzma
2018-09-22T04:21:18.013Z,1537590078.013 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-22T04:21:18.013Z,1537590078.013 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-09-22T04:21:18.013Z,1537590078.013 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-22T04:21:18.015Z,1537590078.015 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-22T04:21:18.015Z,1537590078.015 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-22T04:21:18.015Z,1537590078.015 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-22T04:21:18.028Z,1537590078.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-22T04:21:18.028Z,1537590078.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-22T04:21:18.176Z,1537590078.176 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-22T04:21:18.176Z,1537590078.176 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-09-22T04:21:18.176Z,1537590078.176 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-22T04:21:18.176Z,1537590078.176 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-22T04:21:18.497Z,1537590078.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-22T04:21:18.498Z,1537590078.498 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-22T04:21:18.498Z,1537590078.498 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-22T04:21:18.498Z,1537590078.498 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-22T04:21:18.499Z,1537590078.499 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-22T04:21:18.499Z,1537590078.499 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-22T04:21:21.648Z,1537590081.648 [NAL9602](INFO): SBD MO Status=1, MOMSN=1016, MT Status=0, MTMSN=0
2018-09-22T04:21:21.705Z,1537590081.705 [NAL9602](INFO): Sent 118 bytes from file Logs/20180922T041231/Courier0000.lzma
2018-09-22T04:21:21.705Z,1537590081.705 [NAL9602](INFO): Packets left to send: 0
2018-09-22T04:21:21.707Z,1537590081.707 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180922T041231/Courier0000.lzma.parts/0000.sbd
2018-09-22T04:21:21.707Z,1537590081.707 [NAL9602](DEBUG): Completed sending Logs/20180922T041231/Courier0000.lzma
2018-09-22T04:21:21.794Z,1537590081.794 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-09-22T04:21:21.794Z,1537590081.794 [Aanderaa_O2] No Fault, FailCount= 3
2018-09-22T04:21:29.832Z,1537590089.832 [NAL9602](INFO): SBD MO Status=1, MOMSN=1017, MT Status=0, MTMSN=0
2018-09-22T04:21:29.881Z,1537590089.881 [NAL9602](INFO): Sent 18 bytes from file Logs/20180922T041231/Courier0004.lzma
2018-09-22T04:21:29.881Z,1537590089.881 [NAL9602](INFO): Packets left to send: 0
2018-09-22T04:21:29.883Z,1537590089.883 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180922T041231/Courier0004.lzma.parts/0000.sbd
2018-09-22T04:21:29.883Z,1537590089.883 [NAL9602](DEBUG): Completed sending Logs/20180922T041231/Courier0004.lzma
2018-09-22T04:21:33.208Z,1537590093.208 [Aanderaa_O2](FAULT): Timed out starting
2018-09-22T04:21:33.208Z,1537590093.208 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-09-22T04:21:33.208Z,1537590093.208 [Aanderaa_O2](ERROR): Communications Fault
2018-09-22T04:21:33.344Z,1537590093.344 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-09-22T04:21:33.729Z,1537590093.729 [Aanderaa_O2](INFO): Powering down
2018-09-22T04:21:34.496Z,1537590094.496 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-09-22T04:21:34.496Z,1537590094.496 [Aanderaa_O2] No Fault, FailCount= 1
2018-09-22T04:21:39.228Z,1537590099.228 [NAL9602](INFO): SBD MO Status=1, MOMSN=1018, MT Status=0, MTMSN=0
2018-09-22T04:21:39.273Z,1537590099.273 [NAL9602](INFO): Sent 117 bytes from file Logs/20180921T222158/Express0062.lzma
2018-09-22T04:21:39.274Z,1537590099.274 [NAL9602](INFO): Packets left to send: 0
2018-09-22T04:21:39.275Z,1537590099.275 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180921T222158/Express0062.lzma.parts/0000.sbd
2018-09-22T04:21:39.276Z,1537590099.276 [NAL9602](DEBUG): Completed sending Logs/20180921T222158/Express0062.lzma
2018-09-22T04:21:42.555Z,1537590102.555 [Startup:StartupSatComms:B](INFO): Timed out from 2018-09-22T04:20:40.8Z
2018-09-22T04:21:42.555Z,1537590102.555 [Startup:StartupSatComms:B] Stopped
2018-09-22T04:21:42.555Z,1537590102.555 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-22T04:21:42.556Z,1537590102.556 [Startup:StartupSatComms] Stopped
2018-09-22T04:21:42.556Z,1537590102.556 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-22T04:21:42.557Z,1537590102.557 [Startup](INFO): Completed Startup
2018-09-22T04:21:42.557Z,1537590102.557 [MissionManager](INFO): Startup is completed.
2018-09-22T04:21:42.557Z,1537590102.557 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-22T04:21:42.557Z,1537590102.557 [Startup] Stopped
2018-09-22T04:21:42.557Z,1537590102.557 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-22T04:21:42.557Z,1537590102.557 [Startup:A.GoToSurface] Stopped
2018-09-22T04:21:42.558Z,1537590102.558 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-22T04:21:42.668Z,1537590102.668 [MissionManager](IMPORTANT): Started mission Default
2018-09-22T04:21:42.673Z,1537590102.673 [Default] Running Loop=1
2018-09-22T04:21:42.673Z,1537590102.673 [Default](DEBUG): Aggregate::initialize Default
2018-09-22T04:21:42.673Z,1537590102.673 [Default:B.GoToSurface] Running Loop=1
2018-09-22T04:21:42.673Z,1537590102.673 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-22T04:21:42.674Z,1537590102.674 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-22T04:21:42.674Z,1537590102.674 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-22T04:21:42.674Z,1537590102.674 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-22T04:21:42.675Z,1537590102.675 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-22T04:21:42.675Z,1537590102.675 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-22T04:21:42.675Z,1537590102.675 [Default:A.Wait] Running Loop=1
2018-09-22T04:21:42.676Z,1537590102.676 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-22T04:21:46.378Z,1537590106.378 [Aanderaa_O2](FAULT): Timed out starting
2018-09-22T04:21:46.378Z,1537590106.378 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-09-22T04:21:46.378Z,1537590106.378 [Aanderaa_O2](ERROR): Communications Fault
2018-09-22T04:21:46.523Z,1537590106.523 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-09-22T04:21:46.897Z,1537590106.897 [Aanderaa_O2](INFO): Powering down
2018-09-22T04:21:47.187Z,1537590107.187 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004122
2018-09-22T04:21:47.651Z,1537590107.651 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-09-22T04:21:47.651Z,1537590107.651 [Aanderaa_O2] No Fault, FailCount= 2
2018-09-22T04:21:49.188Z,1537590109.188 [NAL9602](INFO): SBD MO Status=1, MOMSN=1019, MT Status=0, MTMSN=0
2018-09-22T04:21:49.241Z,1537590109.241 [NAL9602](INFO): Sent 332 bytes from file Logs/20180922T041231/Express0001.lzma
2018-09-22T04:21:49.241Z,1537590109.241 [NAL9602](INFO): Packets left to send: 3
2018-09-22T04:21:49.243Z,1537590109.243 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180922T041231/Express0001.lzma.parts/0003.sbd
2018-09-22T04:21:56.037Z,1537590116.037 [Default:A.Wait](INFO): Done Waiting.
2018-09-22T04:21:56.037Z,1537590116.037 [Default:A.Wait] Stopped
2018-09-22T04:21:56.037Z,1537590116.037 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-22T04:21:56.489Z,1537590116.489 [Default:CheckIn] Running Loop=1
2018-09-22T04:21:56.489Z,1537590116.489 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-22T04:21:56.489Z,1537590116.489 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-22T04:21:56.838Z,1537590116.838 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-22T04:21:58.380Z,1537590118.380 [Aanderaa_O2](FAULT): Timed out starting
2018-09-22T04:21:58.380Z,1537590118.380 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-09-22T04:21:58.381Z,1537590118.381 [Aanderaa_O2](ERROR): Communications Fault
2018-09-22T04:21:58.550Z,1537590118.550 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-09-22T04:21:58.551Z,1537590118.551 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-09-22T04:21:58.897Z,1537590118.897 [Aanderaa_O2](INFO): Powering down
2018-09-22T04:22:07.000Z,1537590128.000 [NAL9602](INFO): GPS fix at 20180922T042156: (36.873505, -121.862433)
2018-09-22T04:22:08.054Z,1537590128.054 [Default:CheckIn:Read_GPS] Stopped
2018-09-22T04:22:08.054Z,1537590128.054 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-22T04:22:08.482Z,1537590128.482 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-22T04:22:15.152Z,1537590135.152 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180922T041805/Courier0004.lzma
2018-09-22T04:22:15.942Z,1537590135.942 [DataOverHttps](INFO): Moved sent file to Logs/20180922T041805/Courier0004.lzma.bak
2018-09-22T04:22:15.942Z,1537590135.942 [DataOverHttps](INFO): SBD MOMSN=8558522
2018-09-22T04:22:26.227Z,1537590146.227 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180922T041805/Courier0007.lzma
2018-09-22T04:22:27.001Z,1537590147.001 [DataOverHttps](INFO): Moved sent file to Logs/20180922T041805/Courier0007.lzma.bak
2018-09-22T04:22:27.002Z,1537590147.002 [DataOverHttps](INFO): SBD MOMSN=8558527
2018-09-22T04:22:36.987Z,1537590156.987 [DataOverHttps](INFO): Sending 1222 bytes from file Logs/20180922T041231/Express0001.lzma
2018-09-22T04:22:37.769Z,1537590157.769 [DataOverHttps](INFO): Moved sent file to Logs/20180922T041231/Express0001.lzma.bak
2018-09-22T04:22:37.769Z,1537590157.769 [DataOverHttps](INFO): SBD MOMSN=8558530
2018-09-22T04:22:45.222Z,1537590165.222 [NAL9602](INFO): Powering down
2018-09-22T04:22:48.635Z,1537590168.635 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180922T041231/Express0005.lzma
2018-09-22T04:22:49.420Z,1537590169.420 [DataOverHttps](INFO): Moved sent file to Logs/20180922T041231/Express0005.lzma.bak
2018-09-22T04:22:49.420Z,1537590169.420 [DataOverHttps](INFO): SBD MOMSN=8558573
2018-09-22T04:22:58.569Z,1537590178.569 [DataOverHttps](INFO): Sending 1170 bytes from file Logs/20180922T041805/Express0001.lzma
2018-09-22T04:22:59.338Z,1537590179.338 [DataOverHttps](INFO): Moved sent file to Logs/20180922T041805/Express0001.lzma.bak
2018-09-22T04:22:59.338Z,1537590179.338 [DataOverHttps](INFO): SBD MOMSN=8558670
2018-09-22T04:22:59.422Z,1537590179.422 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   -93,   +29,  -441,-32768,A
2018-09-22T04:23:00.569Z,1537590180.569 [RDI_Pathfinder](ERROR): Failed to parse:

2018-09-22T04:23:09.689Z,1537590189.689 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20180922T041805/Express0005.lzma
2018-09-22T04:23:10.470Z,1537590190.470 [DataOverHttps](INFO): Moved sent file to Logs/20180922T041805/Express0005.lzma.bak
2018-09-22T04:23:10.470Z,1537590190.470 [DataOverHttps](INFO): SBD MOMSN=8558702
2018-09-22T04:23:19.638Z,1537590199.638 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20180922T041805/Express0008.lzma
2018-09-22T04:23:20.426Z,1537590200.426 [DataOverHttps](INFO): Moved sent file to Logs/20180922T041805/Express0008.lzma.bak
2018-09-22T04:23:20.426Z,1537590200.426 [DataOverHttps](INFO): SBD MOMSN=8558713
2018-09-22T04:23:20.932Z,1537590200.932 [Default:CheckIn:Read_Iridium] Stopped
2018-09-22T04:23:20.937Z,1537590200.937 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-22T04:23:20.937Z,1537590200.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-22T04:23:31.341Z,1537590211.341 [CommandLine](IMPORTANT): got command restart system
2018-09-22T04:23:34.665Z,1537590214.665 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-09-22T04:23:34.669Z,1537590214.669 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-22T04:23:34.669Z,1537590214.669 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:34.841Z,1537590214.841 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-22T04:23:34.841Z,1537590214.841 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:34.842Z,1537590214.842 [CommandLine](INFO): Join timeout helper Thread ID is 940
2018-09-22T04:23:34.842Z,1537590214.842 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-22T04:23:34.842Z,1537590214.842 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:34.843Z,1537590214.843 [NavChartDb](INFO): Join timeout helper Thread ID is 941
2018-09-22T04:23:35.153Z,1537590215.153 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-22T04:23:35.153Z,1537590215.153 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.161Z,1537590215.161 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-22T04:23:35.161Z,1537590215.161 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.161Z,1537590215.161 [Radio_Surface](INFO): Join timeout helper Thread ID is 942
2018-09-22T04:23:35.213Z,1537590215.213 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-22T04:23:35.213Z,1537590215.213 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.221Z,1537590215.221 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-09-22T04:23:35.221Z,1537590215.221 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.222Z,1537590215.222 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 943
2018-09-22T04:23:35.222Z,1537590215.222 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-22T04:23:35.222Z,1537590215.222 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-22T04:23:35.223Z,1537590215.223 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.224Z,1537590215.224 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-22T04:23:35.224Z,1537590215.224 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.224Z,1537590215.224 [logger](INFO): Join timeout helper Thread ID is 944
2018-09-22T04:23:35.233Z,1537590215.233 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-22T04:23:35.233Z,1537590215.233 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.242Z,1537590215.242 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-22T04:23:35.242Z,1537590215.242 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.242Z,1537590215.242 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-22T04:23:35.242Z,1537590215.242 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.243Z,1537590215.243 [controlThread](INFO): Join timeout helper Thread ID is 945
2018-09-22T04:23:35.402Z,1537590215.402 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-22T04:23:35.403Z,1537590215.403 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-22T04:23:35.404Z,1537590215.404 [Aanderaa_O2](INFO): Powering down
2018-09-22T04:23:35.406Z,1537590215.406 [AHRS_M2](INFO): Powering down
2018-09-22T04:23:35.478Z,1537590215.478 [NAL9602](INFO): Powering down
2018-09-22T04:23:35.549Z,1537590215.549 [RDI_Pathfinder](INFO): Powering down
2018-09-22T04:23:35.550Z,1537590215.550 [RDI_PathfinderUp](INFO): Powering down
2018-09-22T04:23:35.552Z,1537590215.552 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-22T04:23:35.553Z,1537590215.553 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-22T04:23:35.554Z,1537590215.554 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-22T04:23:35.555Z,1537590215.555 [MissionManager](INFO): Uninitializing Mission Default
2018-09-22T04:23:35.555Z,1537590215.555 [Default] Stopped
2018-09-22T04:23:35.555Z,1537590215.555 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-22T04:23:35.555Z,1537590215.555 [Default:B.GoToSurface] Stopped
2018-09-22T04:23:35.555Z,1537590215.555 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-22T04:23:35.555Z,1537590215.555 [Default:CheckIn] Stopped
2018-09-22T04:23:35.555Z,1537590215.555 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-22T04:23:35.555Z,1537590215.555 [Default:CheckIn:C.Wait] Stopped
2018-09-22T04:23:35.555Z,1537590215.555 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-22T04:23:35.559Z,1537590215.559 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-22T04:23:35.559Z,1537590215.559 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-22T04:23:35.560Z,1537590215.560 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-22T04:23:35.560Z,1537590215.560 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-22T04:23:35.560Z,1537590215.560 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-22T04:23:35.560Z,1537590215.560 [BuoyancyServo](INFO): Powering down
2018-09-22T04:23:35.573Z,1537590215.573 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-09-22T04:23:35.573Z,1537590215.573 [ElevatorServo](INFO): Powering down
2018-09-22T04:23:35.574Z,1537590215.574 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-09-22T04:23:35.574Z,1537590215.574 [MassServo](INFO): Powering down
2018-09-22T04:23:35.575Z,1537590215.575 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-09-22T04:23:35.575Z,1537590215.575 [RudderServo](INFO): Powering down
2018-09-22T04:23:35.576Z,1537590215.576 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-22T04:23:35.576Z,1537590215.576 [ThrusterServo](INFO): Powering down
2018-09-22T04:23:35.577Z,1537590215.577 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-22T04:23:35.578Z,1537590215.578 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-22T04:23:35.578Z,1537590215.578 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-22T04:23:35.579Z,1537590215.579 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.635Z,1537590215.635 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.702Z,1537590215.702 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.747Z,1537590215.747 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-22T04:23:35.819Z,1537590215.819 [logger ThreadHandler](INFO): Thread cancelled.