2019-07-23T04:36:20.505Z,1563856580.505 [Supervisor](DEBUG): Initializing supervisor.
2019-07-23T04:36:20.508Z,1563856580.508 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-07-23T04:36:20.508Z,1563856580.508 [SyncHandler](INFO): Protected caller Thread ID is 2173
2019-07-23T04:36:20.508Z,1563856580.508 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-07-23T04:36:20.509Z,1563856580.509 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-07-23T04:36:20.510Z,1563856580.510 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2174
2019-07-23T04:36:20.512Z,1563856580.512 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-07-23T04:36:20.524Z,1563856580.524 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-07-23T04:36:20.525Z,1563856580.525 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-07-23T04:36:20.525Z,1563856580.525 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2175
2019-07-23T04:36:20.526Z,1563856580.526 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-07-23T04:36:20.527Z,1563856580.527 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-07-23T04:36:20.528Z,1563856580.528 [logger ThreadHandler](INFO): Protected caller Thread ID is 2176
2019-07-23T04:36:20.530Z,1563856580.530 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-07-23T04:36:20.530Z,1563856580.530 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-07-23T04:36:20.532Z,1563856580.532 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-07-23T04:36:20.758Z,1563856580.758 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-07-23T04:36:20.759Z,1563856580.759 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-07-23T04:36:20.840Z,1563856580.840 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-07-23T04:36:21.320Z,1563856581.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-07-23T04:36:21.321Z,1563856581.321 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-07-23T04:36:21.691Z,1563856581.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-07-23T04:36:21.691Z,1563856581.691 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-07-23T04:36:21.790Z,1563856581.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-07-23T04:36:21.791Z,1563856581.791 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-07-23T04:36:22.119Z,1563856582.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-07-23T04:36:22.120Z,1563856582.120 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-07-23T04:36:22.330Z,1563856582.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-07-23T04:36:22.331Z,1563856582.331 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-07-23T04:36:22.827Z,1563856582.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-07-23T04:36:22.827Z,1563856582.827 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-07-23T04:36:22.937Z,1563856582.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-07-23T04:36:22.938Z,1563856582.938 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-07-23T04:36:23.041Z,1563856583.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-07-23T04:36:23.042Z,1563856583.042 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-07-23T04:36:23.706Z,1563856583.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-07-23T04:36:23.707Z,1563856583.707 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-07-23T04:36:24.129Z,1563856584.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-07-23T04:36:24.129Z,1563856584.129 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-07-23T04:36:24.346Z,1563856584.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-07-23T04:36:24.347Z,1563856584.347 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-07-23T04:36:24.500Z,1563856584.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-07-23T04:36:24.501Z,1563856584.501 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-07-23T04:36:24.659Z,1563856584.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-07-23T04:36:24.662Z,1563856584.662 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-07-23T04:36:24.663Z,1563856584.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-07-23T04:36:24.756Z,1563856584.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-07-23T04:36:24.844Z,1563856584.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-07-23T04:36:24.952Z,1563856584.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-07-23T04:36:25.037Z,1563856585.037 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-07-23T04:36:25.135Z,1563856585.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-07-23T04:36:25.241Z,1563856585.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-07-23T04:36:25.509Z,1563856585.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-07-23T04:36:25.629Z,1563856585.629 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-07-23T04:36:26.021Z,1563856586.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-07-23T04:36:26.328Z,1563856586.328 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-07-23T04:36:26.546Z,1563856586.546 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-07-23T04:36:26.788Z,1563856586.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-07-23T04:36:26.790Z,1563856586.790 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-07-23T04:36:26.790Z,1563856586.790 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-07-23T04:36:26.812Z,1563856586.812 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-07-23T04:36:26.885Z,1563856586.885 [VerticalControl](DEBUG): Construct VerticalControl.
2019-07-23T04:36:27.004Z,1563856587.004 [VerticalControl] Loaded
2019-07-23T04:36:27.004Z,1563856587.004 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-07-23T04:36:27.005Z,1563856587.005 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-07-23T04:36:27.079Z,1563856587.079 [HorizontalControl] Loaded
2019-07-23T04:36:27.079Z,1563856587.079 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-07-23T04:36:27.079Z,1563856587.079 [SpeedControl](DEBUG): Construct SpeedControl.
2019-07-23T04:36:27.085Z,1563856587.085 [SpeedControl] Loaded
2019-07-23T04:36:27.086Z,1563856587.086 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-07-23T04:36:27.087Z,1563856587.087 [LoopControl](DEBUG): Construct LoopControl.
2019-07-23T04:36:27.087Z,1563856587.087 [LoopControl] Loaded
2019-07-23T04:36:27.087Z,1563856587.087 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-07-23T04:36:27.088Z,1563856587.088 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-07-23T04:36:27.088Z,1563856587.088 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-07-23T04:36:27.102Z,1563856587.102 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-07-23T04:36:27.103Z,1563856587.103 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-07-23T04:36:27.210Z,1563856587.210 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-07-23T04:36:27.211Z,1563856587.211 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-07-23T04:36:27.343Z,1563856587.343 [BuoyancyServo] Loaded
2019-07-23T04:36:27.343Z,1563856587.343 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-07-23T04:36:27.355Z,1563856587.355 [ElevatorServo] Loaded
2019-07-23T04:36:27.356Z,1563856587.356 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-07-23T04:36:27.367Z,1563856587.367 [MassServo] Loaded
2019-07-23T04:36:27.367Z,1563856587.367 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-07-23T04:36:27.379Z,1563856587.379 [RudderServo] Loaded
2019-07-23T04:36:27.379Z,1563856587.379 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-07-23T04:36:27.391Z,1563856587.391 [ThrusterServo] Loaded
2019-07-23T04:36:27.391Z,1563856587.391 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-07-23T04:36:27.392Z,1563856587.392 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-07-23T04:36:27.392Z,1563856587.392 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-07-23T04:36:27.506Z,1563856587.506 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-07-23T04:36:27.506Z,1563856587.506 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-07-23T04:36:27.529Z,1563856587.529 [NavChart] Loaded
2019-07-23T04:36:27.529Z,1563856587.529 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-07-23T04:36:27.533Z,1563856587.533 [UniversalFixResidualReporter] Loaded
2019-07-23T04:36:27.533Z,1563856587.533 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-07-23T04:36:27.534Z,1563856587.534 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-07-23T04:36:27.534Z,1563856587.534 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-07-23T04:36:27.610Z,1563856587.610 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-07-23T04:36:27.610Z,1563856587.610 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-07-23T04:36:27.961Z,1563856587.961 [DataOverHttps] Loaded
2019-07-23T04:36:27.961Z,1563856587.961 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-07-23T04:36:27.962Z,1563856587.962 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0
2019-07-23T04:36:27.962Z,1563856587.962 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2258
2019-07-23T04:36:27.977Z,1563856587.977 [Depth_Keller] Loaded
2019-07-23T04:36:27.977Z,1563856587.977 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-07-23T04:36:28.080Z,1563856588.080 [NAL9602] Loaded
2019-07-23T04:36:28.080Z,1563856588.080 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-07-23T04:36:28.097Z,1563856588.097 [Onboard] Loaded
2019-07-23T04:36:28.098Z,1563856588.098 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-07-23T04:36:28.101Z,1563856588.101 [Radio_Surface] Loaded
2019-07-23T04:36:28.101Z,1563856588.101 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-07-23T04:36:28.103Z,1563856588.103 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0
2019-07-23T04:36:28.103Z,1563856588.103 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2259
2019-07-23T04:36:29.698Z,1563856589.698 [BPC1] Loaded
2019-07-23T04:36:29.698Z,1563856589.698 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-07-23T04:36:29.699Z,1563856589.699 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-07-23T04:36:29.699Z,1563856589.699 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-07-23T04:36:29.743Z,1563856589.743 [DepthRateCalculator] Loaded
2019-07-23T04:36:29.743Z,1563856589.743 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-07-23T04:36:29.749Z,1563856589.749 [PitchRateCalculator] Loaded
2019-07-23T04:36:29.749Z,1563856589.749 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-07-23T04:36:29.761Z,1563856589.761 [SpeedCalculator] Loaded
2019-07-23T04:36:29.762Z,1563856589.762 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-07-23T04:36:29.784Z,1563856589.784 [TempGradientCalculator] Loaded
2019-07-23T04:36:29.785Z,1563856589.785 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-07-23T04:36:29.790Z,1563856589.790 [YawRateCalculator] Loaded
2019-07-23T04:36:29.790Z,1563856589.790 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-07-23T04:36:29.822Z,1563856589.822 [ElevatorOffsetCalculator] Loaded
2019-07-23T04:36:29.822Z,1563856589.822 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-07-23T04:36:29.823Z,1563856589.823 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-07-23T04:36:29.823Z,1563856589.823 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-07-23T04:36:30.032Z,1563856590.032 [ESPComponent] Loaded
2019-07-23T04:36:30.032Z,1563856590.032 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-07-23T04:36:30.048Z,1563856590.048 [PAR_Licor] Loaded
2019-07-23T04:36:30.048Z,1563856590.048 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-07-23T04:36:30.049Z,1563856590.049 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-07-23T04:36:30.049Z,1563856590.049 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-07-23T04:36:30.083Z,1563856590.083 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-07-23T04:36:30.084Z,1563856590.084 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-07-23T04:36:30.511Z,1563856590.511 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-07-23T04:36:30.512Z,1563856590.512 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-07-23T04:36:30.988Z,1563856590.988 [SBIT](DEBUG): Construct Startup Built In Test.
2019-07-23T04:36:30.000Z,1563856591.000 [SBIT] Loaded
2019-07-23T04:36:30.000Z,1563856591.000 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-07-23T04:36:31.001Z,1563856591.001 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-07-23T04:36:31.013Z,1563856591.013 [IBIT] Loaded
2019-07-23T04:36:31.013Z,1563856591.013 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-07-23T04:36:31.016Z,1563856591.016 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-07-23T04:36:31.166Z,1563856591.166 [CBIT] Loaded
2019-07-23T04:36:31.166Z,1563856591.166 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-07-23T04:36:31.167Z,1563856591.167 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-07-23T04:36:31.170Z,1563856591.170 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-07-23T04:36:31.171Z,1563856591.171 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-07-23T04:36:31.178Z,1563856591.178 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-07-23T04:36:31.179Z,1563856591.179 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7E4E0
2019-07-23T04:36:31.179Z,1563856591.179 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2260
2019-07-23T04:36:31.184Z,1563856591.184 [Supervisor](INFO): Main Thread ID is 802
2019-07-23T04:36:31.184Z,1563856591.184 [Supervisor](DEBUG): Running supervisor.
2019-07-23T04:36:31.185Z,1563856591.185 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2261
2019-07-23T04:36:31.187Z,1563856591.187 [controlThread ThreadHandler](INFO): Handler Thread ID is 2262
2019-07-23T04:36:31.187Z,1563856591.187 [controlThread](DEBUG): Initializing ControlThread
2019-07-23T04:36:31.188Z,1563856591.188 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-07-23T04:36:31.190Z,1563856591.190 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-07-23T04:36:31.191Z,1563856591.191 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-07-23T04:36:31.191Z,1563856591.191 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-07-23T04:36:31.193Z,1563856591.193 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-07-23T04:36:31.194Z,1563856591.194 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-07-23T04:36:31.198Z,1563856591.198 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-07-23T04:36:31.198Z,1563856591.198 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-07-23T04:36:31.198Z,1563856591.198 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-07-23T04:36:31.199Z,1563856591.199 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-07-23T04:36:31.199Z,1563856591.199 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-07-23T04:36:31.199Z,1563856591.199 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-07-23T04:36:31.201Z,1563856591.201 [SBIT](INFO): Initialize SBIT Component.
2019-07-23T04:36:31.202Z,1563856591.202 [SBIT](IMPORTANT): git: 2019-07-16
2019-07-23T04:36:31.202Z,1563856591.202 [SBIT](INFO): git hash: 5dde538a58d6fd4f55501de4ce986b4d4d3b120b
2019-07-23T04:36:31.202Z,1563856591.202 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-07-23T04:36:31.203Z,1563856591.203 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-07-23T04:36:31.204Z,1563856591.204 [SBIT](INFO): Beginning SBIT in 29.000000 seconds.
2019-07-23T04:36:31.205Z,1563856591.205 [IBIT](INFO): Initialize IBIT Component.
2019-07-23T04:36:31.206Z,1563856591.206 [CBIT](DEBUG): Initialize CBIT Component.
2019-07-23T04:36:31.207Z,1563856591.207 [logger ThreadHandler](INFO): Handler Thread ID is 2263
2019-07-23T04:36:31.217Z,1563856591.217 [CBIT](DEBUG): Initialized mux pins.
2019-07-23T04:36:31.217Z,1563856591.217 [CBIT](DEBUG): Initializing the watchdog timer.
2019-07-23T04:36:31.225Z,1563856591.225 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2264
2019-07-23T04:36:31.226Z,1563856591.226 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-07-23T04:36:31.237Z,1563856591.237 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2265
2019-07-23T04:36:31.241Z,1563856591.241 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-07-23T04:36:31.241Z,1563856591.241 [CBIT](DEBUG): Initializing heartbeat.
2019-07-23T04:36:31.249Z,1563856591.249 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2266
2019-07-23T04:36:31.252Z,1563856591.252 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-07-23T04:36:31.252Z,1563856591.252 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-07-23T04:36:31.253Z,1563856591.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-07-23T04:36:31.253Z,1563856591.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-07-23T04:36:31.253Z,1563856591.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-07-23T04:36:31.253Z,1563856591.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-07-23T04:36:31.253Z,1563856591.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-07-23T04:36:31.254Z,1563856591.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-07-23T04:36:31.254Z,1563856591.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-07-23T04:36:31.254Z,1563856591.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-07-23T04:36:31.254Z,1563856591.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-07-23T04:36:31.254Z,1563856591.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-07-23T04:36:31.254Z,1563856591.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-07-23T04:36:31.255Z,1563856591.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-07-23T04:36:31.255Z,1563856591.255 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-07-23T04:36:31.255Z,1563856591.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-07-23T04:36:31.313Z,1563856591.313 [CBIT](DEBUG): Deactivating GF circuits.
2019-07-23T04:36:31.313Z,1563856591.313 [CBIT](DEBUG): Deactivating emergency mode.
2019-07-23T04:36:31.349Z,1563856591.349 [CBIT](DEBUG): Backplane powered.
2019-07-23T04:36:31.350Z,1563856591.350 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-07-23T04:36:31.360Z,1563856591.360 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-23T04:36:31.371Z,1563856591.371 [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-07-23T04:36:31.372Z,1563856591.372 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-07-23T04:36:31.448Z,1563856591.448 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-07-23T04:36:31.449Z,1563856591.449 [Default:A.Wait](DEBUG): Construct Wait.
2019-07-23T04:36:31.450Z,1563856591.450 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-23T04:36:31.493Z,1563856591.493 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-07-23T04:36:31.496Z,1563856591.496 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-07-23T04:36:31.517Z,1563856591.517 [Default:E.Execute](DEBUG): Construct Execute.
2019-07-23T04:36:31.521Z,1563856591.521 [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-07-23T04:36:31.532Z,1563856591.532 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,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-07-23T04:36:31.546Z,1563856591.546 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-07-23T04:36:31.641Z,1563856591.641 [Radio_Surface](INFO): Powering up
2019-07-23T04:36:31.707Z,1563856591.707 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-07-23T04:36:31.723Z,1563856591.723 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-07-23T04:36:31.729Z,1563856591.729 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-07-23T04:36:31.730Z,1563856591.730 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-07-23T04:36:31.737Z,1563856591.737 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-07-23T04:36:31.738Z,1563856591.738 [MassServo](DEBUG): Initializing EZServoServo.
2019-07-23T04:36:31.749Z,1563856591.749 [MassServo](DEBUG): Initializing MassServo.
2019-07-23T04:36:31.750Z,1563856591.750 [RudderServo](DEBUG): Initializing EZServoServo.
2019-07-23T04:36:31.757Z,1563856591.757 [RudderServo](DEBUG): Initializing RudderServo.
2019-07-23T04:36:31.758Z,1563856591.758 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-07-23T04:36:31.769Z,1563856591.769 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-07-23T04:36:32.713Z,1563856592.713 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-07-23T04:36:32.713Z,1563856592.713 [RudderServo](FAULT): Rudder failed to initialize
2019-07-23T04:36:32.713Z,1563856592.713 [RudderServo] Communications Fault, FailCount= 1
2019-07-23T04:36:32.713Z,1563856592.713 [RudderServo](ERROR): Communications Fault
2019-07-23T04:36:32.824Z,1563856592.824 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-07-23T04:36:33.002Z,1563856593.002 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-07-23T04:36:33.002Z,1563856593.002 [RudderServo](INFO): Powering down
2019-07-23T04:36:33.687Z,1563856593.687 [RudderServo](DEBUG): Initializing EZServoServo.
2019-07-23T04:36:33.806Z,1563856593.806 [RudderServo](DEBUG): Initializing RudderServo.
2019-07-23T04:36:33.810Z,1563856593.810 [CBIT](INFO): Clearing failed state for component RudderServo
2019-07-23T04:36:33.810Z,1563856593.810 [RudderServo] No Fault, FailCount= 1
2019-07-23T04:36:41.445Z,1563856601.445 [CBIT](CRITICAL): Environmental Failure. Press:14.738779 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-07-23T04:36:41.446Z,1563856601.446 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-07-23T04:36:41.827Z,1563856601.827 [CommandLine](FAULT): Scheduling is paused
2019-07-23T04:36:41.828Z,1563856601.828 [CBIT](INFO): Critical error at 20190723T043641
2019-07-23T04:36:58.286Z,1563856618.286 [NAL9602](INFO): Powering up NAL9602
2019-07-23T04:37:00.769Z,1563856620.769 [SBIT](IMPORTANT): Beginning Startup BIT
2019-07-23T04:37:00.806Z,1563856620.806 [CBIT](IMPORTANT): Beginning ground fault scan
2019-07-23T04:37:09.282Z,1563856629.282 [NAL9602](INFO): NAL9602 initialized
2019-07-23T04:37:10.109Z,1563856630.109 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:37:11.776Z,1563856631.776 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.014900
CHAN A1 (24V): 4.592929
CHAN A2 (12V): -0.002156
CHAN A3 (5V): -0.001821
CHAN B0 (3.3V): 4.766929
CHAN B1 (3.15aV): -0.000584
CHAN B2 (3.15bV): 0.000055
CHAN B3 (GND): 0.000000
OPEN: -0.000170
Full Scale Calc: 4.765 mA, -1.589 mA
2019-07-23T04:37:54.035Z,1563856674.035 [SBIT](IMPORTANT): SBIT PASSED
2019-07-23T04:37:54.054Z,1563856674.054 [CommandLine](IMPORTANT): got command configSet list
2019-07-23T04:37:54.055Z,1563856674.055 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=6 hour;

2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): CBIT.abortDepth=296 meter;

2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere;

2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): CBIT.stopDepth=288 meter;

2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-07-23T04:37:54.056Z,1563856674.056 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-07-23T04:37:54.058Z,1563856674.058 [CommandLine](IMPORTANT): DAT.ignoreElevationAngle=1 bool;

2019-07-23T04:37:54.058Z,1563856674.058 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-07-23T04:37:54.058Z,1563856674.058 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-07-23T04:37:54.058Z,1563856674.058 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-07-23T04:37:54.058Z,1563856674.058 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2019-07-23T04:37:54.058Z,1563856674.058 [CommandLine](IMPORTANT): Express linearApproximation DAT.range 5.000000 meter;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=274 cubic_centimeter;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): VerticalControl.massDefault=-2.3 millimeter;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-31 millimeter;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter;

2019-07-23T04:37:54.059Z,1563856674.059 [CommandLine](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2019-07-23T04:37:54.434Z,1563856674.434 [MissionManager](IMPORTANT): Started mission Startup
2019-07-23T04:37:54.434Z,1563856674.434 [Startup] Running Loop=1
2019-07-23T04:37:54.434Z,1563856674.434 [Startup](DEBUG): Aggregate::initialize Startup
2019-07-23T04:37:54.434Z,1563856674.434 [Startup:A.GoToSurface] Running Loop=1
2019-07-23T04:37:54.434Z,1563856674.434 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-23T04:37:54.435Z,1563856674.435 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-23T04:37:54.435Z,1563856674.435 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-23T04:37:54.436Z,1563856674.436 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-23T04:37:54.436Z,1563856674.436 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-23T04:37:54.437Z,1563856674.437 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-23T04:37:54.438Z,1563856674.438 [Startup:StartupSatComms] Running Loop=1
2019-07-23T04:37:54.438Z,1563856674.438 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-07-23T04:37:54.438Z,1563856674.438 [Startup:StartupSatComms:A] Running Loop=1
2019-07-23T04:37:54.839Z,1563856674.839 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-07-23T04:38:31.201Z,1563856711.201 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-23T04:38:31.201Z,1563856711.201 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-07-23T04:38:31.201Z,1563856711.201 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-23T04:38:31.211Z,1563856711.211 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-23T04:38:31.610Z,1563856711.610 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-23T04:38:31.610Z,1563856711.610 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-07-23T04:38:46.567Z,1563856726.567 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-07-23T04:38:54.622Z,1563856734.622 [Startup:StartupSatComms:A](INFO): Timed out from 2019-07-23T04:37:54.4Z
2019-07-23T04:38:54.622Z,1563856734.622 [Startup:StartupSatComms:A] Stopped
2019-07-23T04:38:54.623Z,1563856734.623 [Startup:StartupSatComms:B] Running Loop=1
2019-07-23T04:38:55.026Z,1563856735.026 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-23T04:39:09.507Z,1563856749.507 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004798
2019-07-23T04:39:14.516Z,1563856754.516 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190723T025144/Courier0034.lzma
2019-07-23T04:39:15.323Z,1563856755.323 [DataOverHttps](INFO): Moved sent file to Logs/20190723T025144/Courier0034.lzma.bak
2019-07-23T04:39:15.323Z,1563856755.323 [DataOverHttps](INFO): SBD MOMSN=11464694
2019-07-23T04:39:26.648Z,1563856766.648 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20190723T043620/Courier0000.lzma
2019-07-23T04:39:27.454Z,1563856767.454 [DataOverHttps](INFO): Moved sent file to Logs/20190723T043620/Courier0000.lzma.bak
2019-07-23T04:39:27.454Z,1563856767.454 [DataOverHttps](INFO): SBD MOMSN=11464696
2019-07-23T04:39:38.524Z,1563856778.524 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20190723T025144/Express0035.lzma
2019-07-23T04:39:39.330Z,1563856779.330 [DataOverHttps](INFO): Moved sent file to Logs/20190723T025144/Express0035.lzma.bak
2019-07-23T04:39:39.330Z,1563856779.330 [DataOverHttps](INFO): SBD MOMSN=11464700
2019-07-23T04:39:50.614Z,1563856790.614 [DataOverHttps](INFO): Sending 1170 bytes from file Logs/20190723T043620/Express0001.lzma
2019-07-23T04:39:51.418Z,1563856791.418 [DataOverHttps](INFO): Moved sent file to Logs/20190723T043620/Express0001.lzma.bak
2019-07-23T04:39:51.418Z,1563856791.418 [DataOverHttps](INFO): SBD MOMSN=11464703
2019-07-23T04:39:52.805Z,1563856792.805 [Startup:StartupSatComms:B] Stopped
2019-07-23T04:39:52.806Z,1563856792.806 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-07-23T04:39:52.806Z,1563856792.806 [Startup:StartupSatComms] Stopped
2019-07-23T04:39:52.806Z,1563856792.806 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-07-23T04:39:52.807Z,1563856792.807 [Startup](INFO): Completed Startup
2019-07-23T04:39:52.807Z,1563856792.807 [MissionManager](INFO): Startup is completed.
2019-07-23T04:39:52.807Z,1563856792.807 [MissionManager](INFO): Uninitializing Mission Startup
2019-07-23T04:39:52.807Z,1563856792.807 [Startup] Stopped
2019-07-23T04:39:52.807Z,1563856792.807 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-07-23T04:39:52.807Z,1563856792.807 [Startup:A.GoToSurface] Stopped
2019-07-23T04:39:52.807Z,1563856792.807 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-23T04:39:53.229Z,1563856793.229 [MissionManager](IMPORTANT): Started mission Default
2019-07-23T04:39:53.229Z,1563856793.229 [Default] Running Loop=1
2019-07-23T04:39:53.229Z,1563856793.229 [Default](DEBUG): Aggregate::initialize Default
2019-07-23T04:39:53.229Z,1563856793.229 [Default:B.GoToSurface] Running Loop=1
2019-07-23T04:39:53.229Z,1563856793.229 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-23T04:39:53.230Z,1563856793.230 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-23T04:39:53.230Z,1563856793.230 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-23T04:39:53.230Z,1563856793.230 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-23T04:39:53.230Z,1563856793.230 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-23T04:39:53.231Z,1563856793.231 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-23T04:39:53.231Z,1563856793.231 [Default:A.Wait] Running Loop=1
2019-07-23T04:39:53.231Z,1563856793.231 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-23T04:40:06.538Z,1563856806.538 [Default:A.Wait](INFO): Done Waiting.
2019-07-23T04:40:06.539Z,1563856806.539 [Default:A.Wait] Stopped
2019-07-23T04:40:06.539Z,1563856806.539 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-23T04:40:06.995Z,1563856806.995 [Default:CheckIn] Running Loop=1
2019-07-23T04:40:06.995Z,1563856806.995 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-23T04:40:06.995Z,1563856806.995 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-23T04:40:07.407Z,1563856807.407 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-07-23T04:40:32.008Z,1563856832.008 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-23T04:40:32.008Z,1563856832.008 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-07-23T04:40:32.008Z,1563856832.008 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-23T04:40:32.062Z,1563856832.062 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-23T04:40:32.420Z,1563856832.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-23T04:40:32.421Z,1563856832.421 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-07-23T04:42:12.558Z,1563856932.558 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-23T04:42:13.377Z,1563856933.377 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:18.221Z,1563856938.221 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:21.457Z,1563856941.457 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:24.285Z,1563856944.285 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:27.109Z,1563856947.109 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:29.137Z,1563856949.137 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:32.361Z,1563856952.361 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:32.804Z,1563856952.804 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-23T04:42:32.804Z,1563856952.804 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-07-23T04:42:32.804Z,1563856952.804 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-23T04:42:32.827Z,1563856952.827 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-23T04:42:33.237Z,1563856953.237 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-23T04:42:33.237Z,1563856953.237 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-07-23T04:42:35.189Z,1563856955.189 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:38.421Z,1563856958.421 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:41.249Z,1563856961.249 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:44.481Z,1563856964.481 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:47.313Z,1563856967.313 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:50.141Z,1563856970.141 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:52.157Z,1563856972.157 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:55.401Z,1563856975.401 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:42:58.221Z,1563856978.221 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:01.449Z,1563856981.449 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:04.277Z,1563856984.277 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:07.509Z,1563856987.509 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:10.341Z,1563856990.341 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:13.165Z,1563856993.165 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:15.189Z,1563856995.189 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:18.421Z,1563856998.421 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:21.249Z,1563857001.249 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:24.481Z,1563857004.481 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:27.309Z,1563857007.309 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:30.541Z,1563857010.541 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:33.369Z,1563857013.369 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:36.193Z,1563857016.193 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:38.217Z,1563857018.217 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:40.237Z,1563857020.237 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:43.469Z,1563857023.469 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:46.293Z,1563857026.293 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:49.525Z,1563857029.525 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:52.357Z,1563857032.357 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:55.585Z,1563857035.585 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:43:58.417Z,1563857038.417 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:01.241Z,1563857041.241 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:02.070Z,1563857042.070 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-07-23T04:44:02.073Z,1563857042.073 [BPC1](INFO): Received data from all battery sticks.
2019-07-23T04:44:03.261Z,1563857043.261 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:05.285Z,1563857045.285 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:08.517Z,1563857048.517 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:11.345Z,1563857051.345 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:14.577Z,1563857054.577 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:17.401Z,1563857057.401 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:20.633Z,1563857060.633 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:23.465Z,1563857063.465 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:26.289Z,1563857066.289 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:28.309Z,1563857068.309 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:31.545Z,1563857071.545 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:33.604Z,1563857073.604 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-23T04:44:33.604Z,1563857073.604 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-07-23T04:44:33.604Z,1563857073.604 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-23T04:44:33.650Z,1563857073.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-23T04:44:34.001Z,1563857074.001 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-23T04:44:34.001Z,1563857074.001 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-07-23T04:44:34.369Z,1563857074.369 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:37.605Z,1563857077.605 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:40.429Z,1563857080.429 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:43.665Z,1563857083.665 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:45.569Z,1563857085.569 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/depth_test.xml
2019-07-23T04:44:45.570Z,1563857085.570 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/depth_test.xml
2019-07-23T04:44:45.617Z,1563857085.617 [MissionManager](INFO): DefineArg depth_test.MissionTimeout = 30.000000 min
2019-07-23T04:44:45.620Z,1563857085.620 [MissionManager](INFO): DefineArg depth_test.Depth = 2.500000 m
2019-07-23T04:44:45.623Z,1563857085.623 [MissionManager](INFO): DefineArg depth_test.ApproachDepthTimeout = 10.000000 min
2019-07-23T04:44:45.626Z,1563857085.626 [MissionManager](INFO): DefineArg depth_test.DepthDeadband = 0.010000 m
2019-07-23T04:44:45.629Z,1563857085.629 [MissionManager](INFO): DefineArg depth_test.Speed = 0.000000 m/s
2019-07-23T04:44:45.630Z,1563857085.630 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2019-07-23T04:44:45.927Z,1563857085.927 [MissionManager](INFO): DefineArg depth_test:Science.SampleISUS = 0 bool
2019-07-23T04:44:45.931Z,1563857085.931 [MissionManager](INFO): DefineArg depth_test:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2019-07-23T04:44:45.935Z,1563857085.935 [MissionManager](INFO): DefineArg depth_test:Science.SampleISUSminPitch = -60.000000 arcdeg
2019-07-23T04:44:45.938Z,1563857085.938 [MissionManager](INFO): DefineArg depth_test:Science.PeakDetectChlActive = 0 bool
2019-07-23T04:44:45.942Z,1563857085.942 [MissionManager](INFO): DefineArg depth_test:Science.TimeWindowPeakReport = nan min
2019-07-23T04:44:45.944Z,1563857085.944 [MissionManager](INFO): DefineArg depth_test:Science.HighestChlPeakReportActive = 0 bool
2019-07-23T04:44:45.947Z,1563857085.947 [MissionManager](INFO): DefineArg depth_test:Science.PatchTracking = 0 bool
2019-07-23T04:44:45.950Z,1563857085.950 [MissionManager](INFO): DefineArg depth_test:Science.FilterWidthHorizontal = 3.000000 count
2019-07-23T04:44:45.954Z,1563857085.954 [MissionManager](INFO): DefineArg depth_test:Science.NumProfilesSlidingwindow = 100.000000 count
2019-07-23T04:44:45.957Z,1563857085.957 [MissionManager](INFO): DefineArg depth_test:Science.OffPeakFractionHorizontal = 80.000000 %
2019-07-23T04:44:45.959Z,1563857085.959 [MissionManager](INFO): DefineArg depth_test:Science.PeakDetectNO3Active = 0 bool
2019-07-23T04:44:45.962Z,1563857085.962 [MissionManager](INFO): DefineArg depth_test:Science.PeakDetectOilActive = 0 bool
2019-07-23T04:44:45.965Z,1563857085.965 [MissionManager](INFO): DefineArg depth_test:Science.PeakDetectFDOMActive = 0 bool
2019-07-23T04:44:45.968Z,1563857085.968 [MissionManager](INFO): DefineArg depth_test:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2019-07-23T04:44:45.972Z,1563857085.972 [MissionManager](INFO): DefineArg depth_test:Science.EnabledAanderaaO2 = 0.000000 bool
2019-07-23T04:44:45.975Z,1563857085.975 [MissionManager](INFO): DefineArg depth_test:Science.EnabledNeilBrown = 0.000000 bool
2019-07-23T04:44:45.979Z,1563857085.979 [MissionManager](INFO): DefineArg depth_test:Science.EnabledSeabird = 0.000000 bool
2019-07-23T04:44:45.983Z,1563857085.983 [MissionManager](INFO): DefineArg depth_test:Science.EnabledTurbulence_NPS = 0.000000 bool
2019-07-23T04:44:45.986Z,1563857085.986 [MissionManager](INFO): DefineArg depth_test:Science.EnabledWetLabsBB2FL = 0.000000 bool
2019-07-23T04:44:45.990Z,1563857085.990 [MissionManager](INFO): DefineArg depth_test:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2019-07-23T04:44:46.014Z,1563857086.014 [MissionManager](INFO): DefineArg depth_test:Science.EnabledWetLabsUBAT = 0.000000 bool
2019-07-23T04:44:46.021Z,1563857086.021 [MissionManager](INFO): DefineArg depth_test:Science.EnabledISUS = 0.000000 bool
2019-07-23T04:44:46.024Z,1563857086.024 [MissionManager](INFO): DefineArg depth_test:Science.LowPassWindowLength = 20.000000 count
2019-07-23T04:44:46.027Z,1563857086.027 [MissionManager](INFO): DefineArg depth_test:Science.MedianFilterLen = 5.000000 count
2019-07-23T04:44:46.031Z,1563857086.031 [MissionManager](INFO): DefineArg depth_test:Science.PeakChlShallowBound = nan m
2019-07-23T04:44:46.049Z,1563857086.049 [MissionManager](INFO): DefineArg depth_test:Science.PeakChlDeepBound = nan m
2019-07-23T04:44:46.052Z,1563857086.052 [MissionManager](INFO): DefineArg depth_test:Science.PeakShallowBound = nan m
2019-07-23T04:44:46.056Z,1563857086.056 [MissionManager](INFO): DefineArg depth_test:Science.PeakDeepBound = nan m
2019-07-23T04:44:46.059Z,1563857086.059 [MissionManager](INFO): DefineOutput depth_test:Science.PeakChl = 0.000000 ug/l
2019-07-23T04:44:46.102Z,1563857086.102 [MissionManager](INFO): DefineOutput depth_test:Science.PeakChlDepth = 0.000000 m
2019-07-23T04:44:46.122Z,1563857086.122 [MissionManager](INFO): DefineOutput depth_test:Science.PeakChlTemperature = 0.000000 degC
2019-07-23T04:44:46.128Z,1563857086.128 [MissionManager](INFO): DefineOutput depth_test:Science.PeakChlLatitude = nan arcdeg
2019-07-23T04:44:46.131Z,1563857086.131 [MissionManager](INFO): DefineOutput depth_test:Science.PeakChlLongitude = nan arcdeg
2019-07-23T04:44:46.135Z,1563857086.135 [MissionManager](INFO): DefineOutput depth_test:Science.PeakNO3 = 0.000000 umol/l
2019-07-23T04:44:46.142Z,1563857086.142 [MissionManager](INFO): DefineOutput depth_test:Science.PeakNO3Depth = 0.000000 m
2019-07-23T04:44:46.154Z,1563857086.154 [MissionManager](INFO): DefineOutput depth_test:Science.PeakNO3Latitude = nan arcdeg
2019-07-23T04:44:46.159Z,1563857086.159 [MissionManager](INFO): DefineOutput depth_test:Science.PeakNO3Longitude = nan arcdeg
2019-07-23T04:44:46.162Z,1563857086.162 [MissionManager](INFO): DefineOutput depth_test:Science.PatchChl = 0.000000 m
2019-07-23T04:44:46.167Z,1563857086.167 [MissionManager](INFO): DefineOutput depth_test:Science.PatchChlDepth = 0.000000 m
2019-07-23T04:44:46.178Z,1563857086.178 [MissionManager](INFO): DefineOutput depth_test:Science.PatchChlLatitude = nan arcdeg
2019-07-23T04:44:46.197Z,1563857086.197 [MissionManager](INFO): DefineOutput depth_test:Science.PatchChlLongitude = nan arcdeg
2019-07-23T04:44:46.205Z,1563857086.205 [MissionManager](INFO): DefineOutput depth_test:Science.PatchChlDistance = nan m
2019-07-23T04:44:46.213Z,1563857086.213 [MissionManager](INFO): DefineOutput depth_test:Science.PeakOil = 0.000000 kg/m3
2019-07-23T04:44:46.216Z,1563857086.216 [MissionManager](INFO): DefineOutput depth_test:Science.PeakOilDepth = 0.000000 m
2019-07-23T04:44:46.224Z,1563857086.224 [MissionManager](INFO): DefineOutput depth_test:Science.PeakOilLatitude = nan arcdeg
2019-07-23T04:44:46.227Z,1563857086.227 [MissionManager](INFO): DefineOutput depth_test:Science.PeakOilLongitude = nan arcdeg
2019-07-23T04:44:46.243Z,1563857086.243 [MissionManager](INFO): DefineOutput depth_test:Science.PeakFDOM = 0.000000 ppb
2019-07-23T04:44:46.246Z,1563857086.246 [MissionManager](INFO): DefineOutput depth_test:Science.PeakFDOMDepth = 0.000000 m
2019-07-23T04:44:46.258Z,1563857086.258 [MissionManager](INFO): DefineOutput depth_test:Science.PeakFDOMLatitude = nan arcdeg
2019-07-23T04:44:46.265Z,1563857086.265 [MissionManager](INFO): DefineOutput depth_test:Science.PeakFDOMLongitude = nan arcdeg
2019-07-23T04:44:46.268Z,1563857086.268 [MissionManager](INFO): DefineOutput depth_test:Science.PatchFDOMDistance = nan m
2019-07-23T04:44:46.322Z,1563857086.322 [depth_test:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-07-23T04:44:46.350Z,1563857086.350 [depth_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2019-07-23T04:44:46.391Z,1563857086.391 [depth_test:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-07-23T04:44:46.408Z,1563857086.408 [depth_test:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-07-23T04:44:46.438Z,1563857086.438 [depth_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-07-23T04:44:46.487Z,1563857086.487 [depth_test:InitialDive:A.Mass](DEBUG): Construct.
2019-07-23T04:44:46.489Z,1563857086.489 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:46.515Z,1563857086.515 [depth_test:InitialDive:B.Buoyancy](DEBUG): Construct Buoyancy.
2019-07-23T04:44:46.529Z,1563857086.529 [depth_test:InitialDive:C.Pitch](DEBUG): Construct.
2019-07-23T04:44:46.552Z,1563857086.552 [depth_test:InitialDive:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-07-23T04:44:46.585Z,1563857086.585 [depth_test:TargetDepth:B.SetSpeed](DEBUG): Construct.
2019-07-23T04:44:46.589Z,1563857086.589 [depth_test:TargetDepth:C.Pitch](DEBUG): Construct.
2019-07-23T04:44:46.610Z,1563857086.610 [depth_test:TargetDepth:D.Wait](DEBUG): Construct Wait.
2019-07-23T04:44:46.646Z,1563857086.646 [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:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="depth_test">

    <Description>
        Go to designated depth.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth held during drift mode
    </Description><Units:meter/><Value>2.5</Value></DefineArg>

    <DefineArg Name="ApproachDepthTimeout"><Description>
        Maximum time duration for the vehicle to reach the approach depth
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed while sampling.
    </Description><Units:meter/><Value>0.01</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle transit speed
    </Description><Units:meter_per_second/><Value>0</Value></DefineArg>


    <!-- Mission timeout -->

    <Timeout Duration="MissionTimeout"/>

    
    <!-- Start mission sequence -->
    
    <Insert Filename="Insert/Science.xml"></Insert>

    <Aggregate Id="InitialDive">

        <Description>
            Descend from the surface to approach depth using the thruster, limit pitch
            and hold the rudder.
        </Description>

        <Sequence/>

        <Guidance:Mass>
            <Parallel/>
            <Setting><Guidance:Mass.position/><Control:VerticalControl.massDefault/></Setting>
        </Guidance:Mass>
        
        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
        </Guidance:Buoyancy>

        <Guidance:Pitch>
            <Sequence/>
            <Timeout Duration="ApproachDepthTimeout">
                <Syslog Severity="Important">Timed out while trying to reach approach depth at current depth of <Universal:depth/><Units:meter/>.</Syslog>
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                </Guidance:Execute>
            </Timeout>
            <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
        </Guidance:Pitch>

    </Aggregate>


    <Aggregate Id="TargetDepth">

        <Description>
            Maintain target depth and acquire ESP samples.
        </Description>

        <Sequence/>

        <Assign><Parallel/><Control:VerticalControl.depthDeadband/><Arg Name="DepthDeadband"/></Assign>

        <Guidance:SetSpeed>
            <Parallel/>
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
        </Guidance:Pitch>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>
        </Guidance:Wait>

    </Aggregate>

</Mission>


2019-07-23T04:44:46.648Z,1563857086.648 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/depth_test.xml
2019-07-23T04:44:46.932Z,1563857086.932 [Default] Stopped
2019-07-23T04:44:46.932Z,1563857086.932 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-23T04:44:46.932Z,1563857086.932 [Default:B.GoToSurface] Stopped
2019-07-23T04:44:46.932Z,1563857086.932 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-23T04:44:46.932Z,1563857086.932 [Default:CheckIn] Stopped
2019-07-23T04:44:46.932Z,1563857086.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-23T04:44:46.933Z,1563857086.933 [Default:CheckIn:Read_GPS] Stopped
2019-07-23T04:44:46.933Z,1563857086.933 [MissionManager](IMPORTANT): Started mission depth_test
2019-07-23T04:44:46.933Z,1563857086.933 [depth_test] Running Loop=1
2019-07-23T04:44:46.933Z,1563857086.933 [depth_test](DEBUG): Aggregate::initialize depth_test
2019-07-23T04:44:46.933Z,1563857086.933 [depth_test:Science] Running Loop=1
2019-07-23T04:44:46.933Z,1563857086.933 [depth_test:Science](DEBUG): Aggregate::initialize depth_test:Science
2019-07-23T04:44:46.933Z,1563857086.933 [depth_test:Science:A] Running Loop=1
2019-07-23T04:44:46.933Z,1563857086.933 [depth_test:Science:B] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:C] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:D] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:E] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:F] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:G] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:H] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:Read_Oil] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:PeakDetectChl] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:PeakDetectChl](DEBUG): Aggregate::initialize depth_test:Science:PeakDetectChl
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2019-07-23T04:44:46.934Z,1563857086.934 [depth_test:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-07-23T04:44:46.936Z,1563857086.936 [depth_test:Science:HighestChlPeakReport] Running Loop=1
2019-07-23T04:44:46.937Z,1563857086.937 [depth_test:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize depth_test:Science:HighestChlPeakReport
2019-07-23T04:44:46.937Z,1563857086.937 [depth_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2019-07-23T04:44:46.937Z,1563857086.937 [depth_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2019-07-23T04:44:46.937Z,1563857086.937 [depth_test:Science:PeakDetectNO3] Running Loop=1
2019-07-23T04:44:46.937Z,1563857086.937 [depth_test:Science:PeakDetectNO3](DEBUG): Aggregate::initialize depth_test:Science:PeakDetectNO3
2019-07-23T04:44:46.938Z,1563857086.938 [depth_test:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2019-07-23T04:44:46.938Z,1563857086.938 [depth_test:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-07-23T04:44:46.939Z,1563857086.939 [depth_test:Science:PeakDetectOil] Running Loop=1
2019-07-23T04:44:46.940Z,1563857086.940 [depth_test:Science:PeakDetectOil](DEBUG): Aggregate::initialize depth_test:Science:PeakDetectOil
2019-07-23T04:44:46.940Z,1563857086.940 [depth_test:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2019-07-23T04:44:46.940Z,1563857086.940 [depth_test:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-07-23T04:44:46.961Z,1563857086.961 [depth_test:Science:PeakDetectFDOM] Running Loop=1
2019-07-23T04:44:46.961Z,1563857086.961 [depth_test:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize depth_test:Science:PeakDetectFDOM
2019-07-23T04:44:46.961Z,1563857086.961 [depth_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2019-07-23T04:44:46.961Z,1563857086.961 [depth_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-07-23T04:44:46.963Z,1563857086.963 [depth_test:InitialDive] Running Loop=1
2019-07-23T04:44:46.963Z,1563857086.963 [depth_test:InitialDive](DEBUG): Aggregate::initialize depth_test:InitialDive
2019-07-23T04:44:46.963Z,1563857086.963 [depth_test:InitialDive:A.Mass] Running Loop=1
2019-07-23T04:44:46.964Z,1563857086.964 [depth_test:InitialDive:A.Mass](DEBUG): Initialize.
2019-07-23T04:44:46.964Z,1563857086.964 [depth_test:InitialDive:B.Buoyancy] Running Loop=1
2019-07-23T04:44:46.964Z,1563857086.964 [depth_test:InitialDive:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2019-07-23T04:44:46.964Z,1563857086.964 [depth_test:InitialDive:C.Pitch] Running Loop=1
2019-07-23T04:44:46.964Z,1563857086.964 [depth_test:InitialDive:C.Pitch](DEBUG): Initialize.
2019-07-23T04:44:46.965Z,1563857086.965 [depth_test:InitialDive:B.Buoyancy] Running Loop=1
2019-07-23T04:44:46.966Z,1563857086.966 [depth_test:InitialDive:A.Mass] Running Loop=1
2019-07-23T04:44:46.966Z,1563857086.966 [depth_test:Science] Running Loop=1
2019-07-23T04:44:46.967Z,1563857086.967 [depth_test:Science:PeakDetectFDOM] Stopped
2019-07-23T04:44:46.967Z,1563857086.967 [depth_test:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize depth_test:Science:PeakDetectFDOM
2019-07-23T04:44:46.967Z,1563857086.967 [depth_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2019-07-23T04:44:46.967Z,1563857086.967 [depth_test:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-07-23T04:44:46.967Z,1563857086.967 [depth_test:Science:PeakDetectOil] Stopped
2019-07-23T04:44:46.967Z,1563857086.967 [depth_test:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize depth_test:Science:PeakDetectOil
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:PeakDetectNO3] Stopped
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize depth_test:Science:PeakDetectNO3
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:HighestChlPeakReport] Stopped
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize depth_test:Science:HighestChlPeakReport
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:PeakDetectChl] Stopped
2019-07-23T04:44:46.968Z,1563857086.968 [depth_test:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize depth_test:Science:PeakDetectChl
2019-07-23T04:44:46.979Z,1563857086.979 [depth_test:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-07-23T04:44:46.979Z,1563857086.979 [depth_test:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-07-23T04:44:46.979Z,1563857086.979 [depth_test:Science:Read_Oil] Stopped
2019-07-23T04:44:46.979Z,1563857086.979 [depth_test:Science:H] Stopped
2019-07-23T04:44:46.979Z,1563857086.979 [depth_test:Science:G] Stopped
2019-07-23T04:44:46.979Z,1563857086.979 [depth_test:Science:F] Stopped
2019-07-23T04:44:46.979Z,1563857086.979 [depth_test:Science:E] Stopped
2019-07-23T04:44:46.979Z,1563857086.979 [depth_test:Science:D] Stopped
2019-07-23T04:44:46.980Z,1563857086.980 [depth_test:Science:C] Stopped
2019-07-23T04:44:46.980Z,1563857086.980 [depth_test:Science:B] Stopped
2019-07-23T04:44:46.980Z,1563857086.980 [depth_test:Science:A] Stopped
2019-07-23T04:44:49.317Z,1563857089.317 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:51.345Z,1563857091.345 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:54.573Z,1563857094.573 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:44:57.397Z,1563857097.397 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:00.629Z,1563857100.629 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:03.457Z,1563857103.457 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:06.693Z,1563857106.693 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:09.517Z,1563857109.517 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:12.349Z,1563857112.349 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:14.365Z,1563857114.365 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:16.389Z,1563857116.389 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:19.617Z,1563857119.617 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:22.453Z,1563857122.453 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:25.681Z,1563857125.681 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:28.509Z,1563857128.509 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:31.737Z,1563857131.737 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:34.565Z,1563857134.565 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:34.739Z,1563857134.739 [CommandLine](IMPORTANT): got command stop
2019-07-23T04:45:34.739Z,1563857134.739 [CommandLine](IMPORTANT): Scheduling is paused
2019-07-23T04:45:34.739Z,1563857134.739 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-07-23T04:45:35.014Z,1563857135.014 [MissionManager](INFO): MissionManager is completed.
2019-07-23T04:45:35.015Z,1563857135.015 [MissionManager](INFO): Uninitializing Mission depth_test
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test] Stopped
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test](DEBUG): Aggregate::uninitialize depth_test
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test:Science] Stopped
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test:Science](DEBUG): Aggregate::uninitialize depth_test:Science
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test:InitialDive] Stopped
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test:InitialDive](DEBUG): Aggregate::uninitialize depth_test:InitialDive
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test:InitialDive:A.Mass] Stopped
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test:InitialDive:A.Mass](DEBUG): Uninitialize.
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test:InitialDive:B.Buoyancy] Stopped
2019-07-23T04:45:35.015Z,1563857135.015 [depth_test:InitialDive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2019-07-23T04:45:35.016Z,1563857135.016 [depth_test:InitialDive:C.Pitch] Stopped
2019-07-23T04:45:35.394Z,1563857135.394 [MissionManager](IMPORTANT): Started mission Default
2019-07-23T04:45:35.394Z,1563857135.394 [Default] Running Loop=1
2019-07-23T04:45:35.394Z,1563857135.394 [Default](DEBUG): Aggregate::initialize Default
2019-07-23T04:45:35.394Z,1563857135.394 [Default:B.GoToSurface] Running Loop=1
2019-07-23T04:45:35.394Z,1563857135.394 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-23T04:45:35.395Z,1563857135.395 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-23T04:45:35.395Z,1563857135.395 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-23T04:45:35.395Z,1563857135.395 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-23T04:45:35.396Z,1563857135.396 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-23T04:45:35.396Z,1563857135.396 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-23T04:45:35.396Z,1563857135.396 [Default:A.Wait] Running Loop=1
2019-07-23T04:45:35.397Z,1563857135.397 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-23T04:45:37.397Z,1563857137.397 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:39.417Z,1563857139.417 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:42.645Z,1563857142.645 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:45.477Z,1563857145.477 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:48.705Z,1563857148.705 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:48.730Z,1563857148.730 [Default:A.Wait](INFO): Done Waiting.
2019-07-23T04:45:48.731Z,1563857148.731 [Default:A.Wait] Stopped
2019-07-23T04:45:48.731Z,1563857148.731 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-23T04:45:49.142Z,1563857149.142 [Default:CheckIn] Running Loop=1
2019-07-23T04:45:49.143Z,1563857149.143 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-23T04:45:49.143Z,1563857149.143 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-23T04:45:51.537Z,1563857151.537 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:54.765Z,1563857154.765 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:57.597Z,1563857157.597 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:00.421Z,1563857160.421 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:02.441Z,1563857162.441 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:04.461Z,1563857164.461 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:07.693Z,1563857167.693 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:10.521Z,1563857170.521 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:13.757Z,1563857173.757 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:16.581Z,1563857176.581 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:19.813Z,1563857179.813 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:22.641Z,1563857182.641 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:25.473Z,1563857185.473 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:27.489Z,1563857187.489 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:30.721Z,1563857190.721 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:33.549Z,1563857193.549 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:34.808Z,1563857194.808 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-23T04:46:34.808Z,1563857194.808 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-07-23T04:46:34.808Z,1563857194.808 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-23T04:46:34.818Z,1563857194.818 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-23T04:46:35.205Z,1563857195.205 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-23T04:46:35.205Z,1563857195.205 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-07-23T04:46:36.781Z,1563857196.781 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:39.609Z,1563857199.609 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:42.845Z,1563857202.845 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:45.669Z,1563857205.669 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:48.505Z,1563857208.505 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:50.517Z,1563857210.517 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:53.749Z,1563857213.749 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:56.577Z,1563857216.577 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:46:59.813Z,1563857219.813 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:47:02.637Z,1563857222.637 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:47:05.869Z,1563857225.869 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:47:08.697Z,1563857228.697 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:47:11.518Z,1563857231.518 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-23T04:47:11.518Z,1563857231.518 [NAL9602] Data Fault, FailCount= 1
2019-07-23T04:47:11.518Z,1563857231.518 [NAL9602](ERROR): Data Fault
2019-07-23T04:47:11.548Z,1563857231.548 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-23T04:47:11.918Z,1563857231.918 [NAL9602](INFO): Powering down
2019-07-23T04:47:12.761Z,1563857232.761 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-23T04:47:12.761Z,1563857232.761 [NAL9602] No Fault, FailCount= 1
2019-07-23T04:47:42.222Z,1563857262.222 [NAL9602](INFO): Powering up NAL9602
2019-07-23T04:47:53.130Z,1563857273.130 [NAL9602](INFO): NAL9602 initialized
2019-07-23T04:47:53.953Z,1563857273.953 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:47:56.773Z,1563857276.773 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:47:59.605Z,1563857279.605 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:02.833Z,1563857282.833 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:05.661Z,1563857285.661 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:08.897Z,1563857288.897 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:11.721Z,1563857291.721 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:14.957Z,1563857294.957 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:17.785Z,1563857297.785 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:20.613Z,1563857300.613 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:23.841Z,1563857303.841 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:26.673Z,1563857306.673 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:29.901Z,1563857309.901 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:32.733Z,1563857312.733 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:35.957Z,1563857315.957 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:35.984Z,1563857315.984 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-23T04:48:35.984Z,1563857315.984 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-07-23T04:48:35.984Z,1563857315.984 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-23T04:48:36.036Z,1563857316.036 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-23T04:48:36.421Z,1563857316.421 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-23T04:48:36.421Z,1563857316.421 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-07-23T04:48:38.793Z,1563857318.793 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:41.617Z,1563857321.617 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:43.641Z,1563857323.641 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:45.657Z,1563857325.657 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:48.893Z,1563857328.893 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:51.721Z,1563857331.721 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:54.953Z,1563857334.953 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:48:57.777Z,1563857337.777 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:01.013Z,1563857341.013 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:03.837Z,1563857343.837 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:06.665Z,1563857346.665 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:08.685Z,1563857348.685 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:11.921Z,1563857351.921 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:14.745Z,1563857354.745 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:17.981Z,1563857357.981 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:20.809Z,1563857360.809 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:24.041Z,1563857364.041 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:26.865Z,1563857366.865 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:29.697Z,1563857369.697 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:31.713Z,1563857371.713 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:34.945Z,1563857374.945 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:37.773Z,1563857377.773 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:41.009Z,1563857381.009 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:43.833Z,1563857383.833 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:47.069Z,1563857387.069 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:49.897Z,1563857389.897 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:52.725Z,1563857392.725 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:54.745Z,1563857394.745 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:56.761Z,1563857396.761 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:49:59.997Z,1563857399.997 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:02.825Z,1563857402.825 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:06.053Z,1563857406.053 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:08.885Z,1563857408.885 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:12.117Z,1563857412.117 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:14.945Z,1563857414.945 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:17.769Z,1563857417.769 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:19.793Z,1563857419.793 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:23.025Z,1563857423.025 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:25.853Z,1563857425.853 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:29.085Z,1563857429.085 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:31.913Z,1563857431.913 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:35.141Z,1563857435.141 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:37.169Z,1563857437.169 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-23T04:50:37.169Z,1563857437.169 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-07-23T04:50:37.169Z,1563857437.169 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-23T04:50:37.179Z,1563857437.179 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-23T04:50:37.590Z,1563857437.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-23T04:50:37.590Z,1563857437.590 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-07-23T04:50:37.973Z,1563857437.973 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:40.801Z,1563857440.801 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:42.821Z,1563857442.821 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:44.841Z,1563857444.841 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:48.072Z,1563857448.072 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:49.294Z,1563857449.294 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-23T04:45:49.1Z
2019-07-23T04:50:49.295Z,1563857449.295 [Default:CheckIn:Read_GPS] Stopped
2019-07-23T04:50:49.295Z,1563857449.295 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-23T04:50:49.699Z,1563857449.699 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-23T04:50:50.901Z,1563857450.901 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:54.129Z,1563857454.129 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:50:54.696Z,1563857454.696 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20190723T043620/Courier0004.lzma
2019-07-23T04:50:55.502Z,1563857455.502 [DataOverHttps](INFO): Moved sent file to Logs/20190723T043620/Courier0004.lzma.bak
2019-07-23T04:50:55.503Z,1563857455.503 [DataOverHttps](INFO): SBD MOMSN=11464747
2019-07-23T04:50:56.957Z,1563857456.957 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:00.189Z,1563857460.189 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:03.017Z,1563857463.017 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:05.845Z,1563857465.845 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:06.487Z,1563857466.487 [DataOverHttps](INFO): Sending 411 bytes from file Logs/20190723T043620/Express0005.lzma
2019-07-23T04:51:07.290Z,1563857467.290 [DataOverHttps](INFO): Moved sent file to Logs/20190723T043620/Express0005.lzma.bak
2019-07-23T04:51:07.290Z,1563857467.290 [DataOverHttps](INFO): SBD MOMSN=11464749
2019-07-23T04:51:07.865Z,1563857467.865 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:08.729Z,1563857468.729 [Default:CheckIn:Read_Iridium] Stopped
2019-07-23T04:51:08.729Z,1563857468.729 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-23T04:51:08.729Z,1563857468.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-23T04:51:11.097Z,1563857471.097 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:13.929Z,1563857473.929 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:17.157Z,1563857477.157 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:19.989Z,1563857479.989 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:23.221Z,1563857483.221 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:26.045Z,1563857486.045 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:28.877Z,1563857488.877 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:30.897Z,1563857490.897 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:34.125Z,1563857494.125 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:36.957Z,1563857496.957 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:40.185Z,1563857500.185 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:43.013Z,1563857503.013 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:46.245Z,1563857506.245 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:49.081Z,1563857509.081 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:51.905Z,1563857511.905 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:53.925Z,1563857513.925 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:55.941Z,1563857515.941 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:51:59.173Z,1563857519.173 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:02.001Z,1563857522.001 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:05.229Z,1563857525.229 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:08.061Z,1563857528.061 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:11.293Z,1563857531.293 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:14.121Z,1563857534.121 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:16.949Z,1563857536.949 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:18.969Z,1563857538.969 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:20.993Z,1563857540.993 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:24.225Z,1563857544.225 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:27.053Z,1563857547.053 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:30.285Z,1563857550.285 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:33.113Z,1563857553.113 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:36.345Z,1563857556.345 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:38.371Z,1563857558.371 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-07-23T04:52:38.371Z,1563857558.371 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-07-23T04:52:38.371Z,1563857558.371 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-23T04:52:38.381Z,1563857558.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-23T04:52:38.845Z,1563857558.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-23T04:52:38.845Z,1563857558.845 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-07-23T04:52:39.169Z,1563857559.169 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:41.997Z,1563857561.997 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:44.041Z,1563857564.041 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:47.249Z,1563857567.249 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:50.081Z,1563857570.081 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:53.313Z,1563857573.313 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:56.141Z,1563857576.141 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:52:59.373Z,1563857579.373 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:26.047Z,1563857126.047 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:28.879Z,1563857128.879 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:30.895Z,1563857130.895 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:34.123Z,1563857134.123 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:36.013Z,1563857136.013 [CommandLine](IMPORTANT): got command restart application
2019-07-23T04:45:36.955Z,1563857136.955 [NAL9602](DEBUG): Fix Requested
2019-07-23T04:45:37.019Z,1563857137.019 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-23T04:45:37.020Z,1563857137.020 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:37.123Z,1563857137.123 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-07-23T04:45:37.123Z,1563857137.123 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:37.124Z,1563857137.124 [CommandLine](INFO): Join timeout helper Thread ID is 2325
2019-07-23T04:45:37.125Z,1563857137.125 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-07-23T04:45:37.125Z,1563857137.125 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:37.125Z,1563857137.125 [NavChartDb](INFO): Join timeout helper Thread ID is 2326
2019-07-23T04:45:37.507Z,1563857137.507 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-23T04:45:37.508Z,1563857137.508 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:37.515Z,1563857137.515 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-07-23T04:45:37.515Z,1563857137.515 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:37.516Z,1563857137.516 [Radio_Surface](INFO): Join timeout helper Thread ID is 2327
2019-07-23T04:45:37.911Z,1563857137.911 [Radio_Surface](INFO): Powering down
2019-07-23T04:45:37.912Z,1563857137.912 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-23T04:45:37.913Z,1563857137.913 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:37.915Z,1563857137.915 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-07-23T04:45:37.915Z,1563857137.915 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:37.916Z,1563857137.916 [DataOverHttps](INFO): Join timeout helper Thread ID is 2328
2019-07-23T04:45:38.007Z,1563857138.007 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-23T04:45:38.008Z,1563857138.008 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.015Z,1563857138.015 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-07-23T04:45:38.015Z,1563857138.015 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.016Z,1563857138.016 [logger](INFO): Join timeout helper Thread ID is 2329
2019-07-23T04:45:38.043Z,1563857138.043 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-23T04:45:38.044Z,1563857138.044 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.056Z,1563857138.056 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-07-23T04:45:38.056Z,1563857138.056 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.056Z,1563857138.056 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-07-23T04:45:38.056Z,1563857138.056 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.057Z,1563857138.057 [controlThread](INFO): Join timeout helper Thread ID is 2330
2019-07-23T04:45:38.147Z,1563857138.147 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-23T04:45:38.148Z,1563857138.148 [controlThread](DEBUG): Uninitializing ControlThread
2019-07-23T04:45:38.148Z,1563857138.148 [NAL9602](INFO): Powering down
2019-07-23T04:45:38.170Z,1563857138.170 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-07-23T04:45:38.171Z,1563857138.171 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-07-23T04:45:38.171Z,1563857138.171 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-07-23T04:45:38.172Z,1563857138.172 [MissionManager](INFO): Uninitializing Mission Default
2019-07-23T04:45:38.172Z,1563857138.172 [Default] Stopped
2019-07-23T04:45:38.172Z,1563857138.172 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-23T04:45:38.172Z,1563857138.172 [Default:B.GoToSurface] Stopped
2019-07-23T04:45:38.172Z,1563857138.172 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-23T04:45:38.172Z,1563857138.172 [Default:CheckIn] Stopped
2019-07-23T04:45:38.172Z,1563857138.172 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-23T04:45:38.172Z,1563857138.172 [Default:CheckIn:C.Wait] Stopped
2019-07-23T04:45:38.172Z,1563857138.172 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-23T04:45:38.174Z,1563857138.174 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-07-23T04:45:38.175Z,1563857138.175 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-07-23T04:45:38.175Z,1563857138.175 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-07-23T04:45:38.176Z,1563857138.176 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-07-23T04:45:38.176Z,1563857138.176 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-07-23T04:45:38.176Z,1563857138.176 [BuoyancyServo](INFO): Powering down
2019-07-23T04:45:38.187Z,1563857138.187 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-07-23T04:45:38.187Z,1563857138.187 [ElevatorServo](INFO): Powering down
2019-07-23T04:45:38.188Z,1563857138.188 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-07-23T04:45:38.188Z,1563857138.188 [MassServo](INFO): Powering down
2019-07-23T04:45:38.189Z,1563857138.189 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-07-23T04:45:38.189Z,1563857138.189 [RudderServo](INFO): Powering down
2019-07-23T04:45:38.190Z,1563857138.190 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-07-23T04:45:38.190Z,1563857138.190 [ThrusterServo](INFO): Powering down
2019-07-23T04:45:38.191Z,1563857138.191 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-07-23T04:45:38.191Z,1563857138.191 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-07-23T04:45:38.192Z,1563857138.192 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-07-23T04:45:38.192Z,1563857138.192 [CBIT](DEBUG): Powering off loads.
2019-07-23T04:45:38.203Z,1563857138.203 [CBIT](DEBUG): Disabling WDT.
2019-07-23T04:45:38.215Z,1563857138.215 [CBIT](DEBUG): Opening all GF detection circuits.
2019-07-23T04:45:38.216Z,1563857138.216 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.304Z,1563857138.304 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.310Z,1563857138.310 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.370Z,1563857138.370 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-23T04:45:38.425Z,1563857138.425 [logger ThreadHandler](INFO): Thread cancelled.