2018-10-16T05:30:08.737Z,1539667808.737 [Supervisor](DEBUG): Initializing supervisor.
2018-10-16T05:30:08.740Z,1539667808.740 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-16T05:30:08.741Z,1539667808.741 [SyncHandler](INFO): Protected caller Thread ID is 6178
2018-10-16T05:30:08.742Z,1539667808.742 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-16T05:30:08.743Z,1539667808.743 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-16T05:30:08.743Z,1539667808.743 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6179
2018-10-16T05:30:08.746Z,1539667808.746 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-16T05:30:08.758Z,1539667808.758 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-16T05:30:08.759Z,1539667808.759 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-16T05:30:08.759Z,1539667808.759 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6180
2018-10-16T05:30:08.760Z,1539667808.760 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-16T05:30:08.761Z,1539667808.761 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-16T05:30:08.761Z,1539667808.761 [logger ThreadHandler](INFO): Protected caller Thread ID is 6181
2018-10-16T05:30:08.763Z,1539667808.763 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-16T05:30:08.764Z,1539667808.764 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-16T05:30:08.765Z,1539667808.765 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-16T05:30:09.087Z,1539667809.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-16T05:30:09.088Z,1539667809.088 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-16T05:30:09.283Z,1539667809.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-16T05:30:09.283Z,1539667809.283 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-16T05:30:09.577Z,1539667809.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-16T05:30:09.578Z,1539667809.578 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-16T05:30:09.721Z,1539667809.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-16T05:30:09.722Z,1539667809.722 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-16T05:30:09.819Z,1539667809.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-16T05:30:09.820Z,1539667809.820 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-16T05:30:10.273Z,1539667810.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-16T05:30:10.274Z,1539667810.274 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-16T05:30:10.727Z,1539667810.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-16T05:30:10.728Z,1539667810.728 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-16T05:30:11.188Z,1539667811.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-16T05:30:11.189Z,1539667811.189 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-16T05:30:11.291Z,1539667811.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-16T05:30:11.291Z,1539667811.291 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-16T05:30:11.387Z,1539667811.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-16T05:30:11.387Z,1539667811.387 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-16T05:30:11.635Z,1539667811.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-16T05:30:11.636Z,1539667811.636 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-16T05:30:12.097Z,1539667812.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-16T05:30:12.098Z,1539667812.098 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-16T05:30:12.445Z,1539667812.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-16T05:30:12.446Z,1539667812.446 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-16T05:30:12.590Z,1539667812.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-16T05:30:12.590Z,1539667812.590 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-16T05:30:12.671Z,1539667812.671 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-10-16T05:30:12.672Z,1539667812.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-10-16T05:30:12.774Z,1539667812.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-10-16T05:30:12.890Z,1539667812.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-10-16T05:30:12.984Z,1539667812.984 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-10-16T05:30:13.068Z,1539667813.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-10-16T05:30:13.230Z,1539667813.230 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-10-16T05:30:13.407Z,1539667813.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-10-16T05:30:13.482Z,1539667813.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-10-16T05:30:13.710Z,1539667813.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-16T05:30:13.711Z,1539667813.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-10-16T05:30:13.793Z,1539667813.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-10-16T05:30:13.883Z,1539667813.883 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-10-16T05:30:13.983Z,1539667813.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-10-16T05:30:14.109Z,1539667814.109 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-10-16T05:30:14.109Z,1539667814.109 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-16T05:30:14.121Z,1539667814.121 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-16T05:30:14.360Z,1539667814.360 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-16T05:30:14.361Z,1539667814.361 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-16T05:30:14.402Z,1539667814.402 [DepthRateCalculator] Loaded
2018-10-16T05:30:14.403Z,1539667814.403 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-16T05:30:14.409Z,1539667814.409 [PitchRateCalculator] Loaded
2018-10-16T05:30:14.409Z,1539667814.409 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-16T05:30:14.425Z,1539667814.425 [SpeedCalculator] Loaded
2018-10-16T05:30:14.425Z,1539667814.425 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-16T05:30:14.446Z,1539667814.446 [TempGradientCalculator] Loaded
2018-10-16T05:30:14.447Z,1539667814.447 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-16T05:30:14.452Z,1539667814.452 [YawRateCalculator] Loaded
2018-10-16T05:30:14.452Z,1539667814.452 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-16T05:30:14.492Z,1539667814.492 [ElevatorOffsetCalculator] Loaded
2018-10-16T05:30:14.492Z,1539667814.492 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-16T05:30:14.492Z,1539667814.492 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-16T05:30:14.493Z,1539667814.493 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-16T05:30:14.557Z,1539667814.557 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-16T05:30:14.558Z,1539667814.558 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-16T05:30:14.668Z,1539667814.668 [BuoyancyServo] Loaded
2018-10-16T05:30:14.668Z,1539667814.668 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-16T05:30:14.683Z,1539667814.683 [ElevatorServo] Loaded
2018-10-16T05:30:14.683Z,1539667814.683 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-16T05:30:14.697Z,1539667814.697 [MassServo] Loaded
2018-10-16T05:30:14.698Z,1539667814.698 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-16T05:30:14.713Z,1539667814.713 [RudderServo] Loaded
2018-10-16T05:30:14.713Z,1539667814.713 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-16T05:30:14.727Z,1539667814.727 [ThrusterServo] Loaded
2018-10-16T05:30:14.728Z,1539667814.728 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-16T05:30:14.728Z,1539667814.728 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-16T05:30:14.729Z,1539667814.729 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-16T05:30:14.742Z,1539667814.742 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-16T05:30:14.742Z,1539667814.742 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-16T05:30:14.842Z,1539667814.842 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-16T05:30:14.843Z,1539667814.843 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-16T05:30:15.140Z,1539667815.140 [DataOverHttps] Loaded
2018-10-16T05:30:15.141Z,1539667815.141 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-16T05:30:15.154Z,1539667815.154 [Depth_Keller] Loaded
2018-10-16T05:30:15.154Z,1539667815.154 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-16T05:30:15.159Z,1539667815.159 [DropWeight] Loaded
2018-10-16T05:30:15.159Z,1539667815.159 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-16T05:30:15.256Z,1539667815.256 [NAL9602] Loaded
2018-10-16T05:30:15.257Z,1539667815.257 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-16T05:30:15.273Z,1539667815.273 [Onboard] Loaded
2018-10-16T05:30:15.273Z,1539667815.273 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-16T05:30:15.283Z,1539667815.283 [Radio_Surface] Loaded
2018-10-16T05:30:15.284Z,1539667815.284 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-16T05:30:15.285Z,1539667815.285 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0
2018-10-16T05:30:15.285Z,1539667815.285 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6263
2018-10-16T05:30:15.331Z,1539667815.331 [PNI_TCM] Loaded
2018-10-16T05:30:15.331Z,1539667815.331 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-10-16T05:30:15.391Z,1539667815.391 [Rowe_600LCM] Loaded
2018-10-16T05:30:15.392Z,1539667815.392 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-10-16T05:30:15.392Z,1539667815.392 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0
2018-10-16T05:30:15.393Z,1539667815.393 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6264
2018-10-16T05:30:15.393Z,1539667815.393 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-16T05:30:15.394Z,1539667815.394 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-16T05:30:15.522Z,1539667815.522 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-16T05:30:15.544Z,1539667815.544 [SBIT] Loaded
2018-10-16T05:30:15.544Z,1539667815.544 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-16T05:30:15.545Z,1539667815.545 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-16T05:30:15.557Z,1539667815.557 [IBIT] Loaded
2018-10-16T05:30:15.557Z,1539667815.557 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-16T05:30:15.561Z,1539667815.561 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-16T05:30:15.707Z,1539667815.707 [CBIT] Loaded
2018-10-16T05:30:15.708Z,1539667815.708 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-16T05:30:15.708Z,1539667815.708 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-16T05:30:15.708Z,1539667815.708 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-16T05:30:15.870Z,1539667815.870 [Aanderaa_O2] Loaded
2018-10-16T05:30:15.871Z,1539667815.871 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-10-16T05:30:15.885Z,1539667815.885 [PAR_Licor] Loaded
2018-10-16T05:30:15.885Z,1539667815.885 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-10-16T05:30:15.934Z,1539667815.934 [WetLabsBB2FL] Loaded
2018-10-16T05:30:15.934Z,1539667815.934 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-16T05:30:15.935Z,1539667815.935 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0
2018-10-16T05:30:15.936Z,1539667815.936 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6265
2018-10-16T05:30:15.936Z,1539667815.936 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-16T05:30:15.937Z,1539667815.937 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-16T05:30:16.004Z,1539667816.004 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-16T05:30:16.086Z,1539667816.086 [VerticalControl] Loaded
2018-10-16T05:30:16.087Z,1539667816.087 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-16T05:30:16.087Z,1539667816.087 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-16T05:30:16.144Z,1539667816.144 [HorizontalControl] Loaded
2018-10-16T05:30:16.144Z,1539667816.144 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-16T05:30:16.145Z,1539667816.145 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-16T05:30:16.146Z,1539667816.146 [SpeedControl] Loaded
2018-10-16T05:30:16.147Z,1539667816.147 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-16T05:30:16.147Z,1539667816.147 [LoopControl](DEBUG): Construct LoopControl.
2018-10-16T05:30:16.148Z,1539667816.148 [LoopControl] Loaded
2018-10-16T05:30:16.148Z,1539667816.148 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-16T05:30:16.148Z,1539667816.148 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-16T05:30:16.149Z,1539667816.149 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-16T05:30:16.173Z,1539667816.173 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-16T05:30:16.174Z,1539667816.174 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-16T05:30:16.262Z,1539667816.262 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-16T05:30:16.263Z,1539667816.263 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-16T05:30:16.279Z,1539667816.279 [NavChart] Loaded
2018-10-16T05:30:16.279Z,1539667816.279 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-16T05:30:16.283Z,1539667816.283 [UniversalFixResidualReporter] Loaded
2018-10-16T05:30:16.283Z,1539667816.283 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-16T05:30:16.283Z,1539667816.283 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-16T05:30:16.287Z,1539667816.287 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-16T05:30:16.288Z,1539667816.288 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-16T05:30:16.295Z,1539667816.295 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-16T05:30:16.296Z,1539667816.296 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0
2018-10-16T05:30:16.296Z,1539667816.296 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6266
2018-10-16T05:30:16.301Z,1539667816.301 [Supervisor](INFO): Main Thread ID is 5405
2018-10-16T05:30:16.301Z,1539667816.301 [Supervisor](DEBUG): Running supervisor.
2018-10-16T05:30:16.302Z,1539667816.302 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6267
2018-10-16T05:30:16.304Z,1539667816.304 [controlThread ThreadHandler](INFO): Handler Thread ID is 6268
2018-10-16T05:30:16.305Z,1539667816.305 [controlThread](DEBUG): Initializing ControlThread
2018-10-16T05:30:16.306Z,1539667816.306 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-16T05:30:16.306Z,1539667816.306 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-16T05:30:16.306Z,1539667816.306 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-16T05:30:16.307Z,1539667816.307 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-16T05:30:16.307Z,1539667816.307 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-16T05:30:16.308Z,1539667816.308 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-16T05:30:16.313Z,1539667816.313 [SBIT](INFO): Initialize SBIT Component.
2018-10-16T05:30:16.313Z,1539667816.313 [SBIT](IMPORTANT): git: 2018-10-09-22-g812aa5f
2018-10-16T05:30:16.313Z,1539667816.313 [SBIT](INFO): git hash: 812aa5f738ae24d7d3f6c5c137b2640d1960a87f
2018-10-16T05:30:16.314Z,1539667816.314 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-16T05:30:16.314Z,1539667816.314 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-16T05:30:16.315Z,1539667816.315 [SBIT](INFO): Beginning SBIT in 36.000000 seconds.
2018-10-16T05:30:16.316Z,1539667816.316 [IBIT](INFO): Initialize IBIT Component.
2018-10-16T05:30:16.316Z,1539667816.316 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-16T05:30:16.317Z,1539667816.317 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-16T05:30:16.318Z,1539667816.318 [logger ThreadHandler](INFO): Handler Thread ID is 6269
2018-10-16T05:30:16.335Z,1539667816.335 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6270
2018-10-16T05:30:16.340Z,1539667816.340 [Radio_Surface](INFO): Powering up
2018-10-16T05:30:16.347Z,1539667816.347 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6271
2018-10-16T05:30:16.347Z,1539667816.347 [Rowe_600LCM](INFO): Initializing
2018-10-16T05:30:16.348Z,1539667816.348 [Rowe_600LCM](INFO): Checking LCM
2018-10-16T05:30:16.357Z,1539667816.357 [Rowe_600LCM](INFO): LCM OK
2018-10-16T05:30:16.357Z,1539667816.357 [Rowe_600LCM](INFO): Powering up
2018-10-16T05:30:16.363Z,1539667816.363 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6272
2018-10-16T05:30:16.364Z,1539667816.364 [WetLabsBB2FL](INFO): Powering down
2018-10-16T05:30:16.391Z,1539667816.391 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6273
2018-10-16T05:30:16.394Z,1539667816.394 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-16T05:30:16.394Z,1539667816.394 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-16T05:30:16.394Z,1539667816.394 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-16T05:30:16.395Z,1539667816.395 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-16T05:30:16.395Z,1539667816.395 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-16T05:30:16.395Z,1539667816.395 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-16T05:30:16.395Z,1539667816.395 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-16T05:30:16.395Z,1539667816.395 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-16T05:30:16.396Z,1539667816.396 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-16T05:30:16.396Z,1539667816.396 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-16T05:30:16.396Z,1539667816.396 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-16T05:30:16.396Z,1539667816.396 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-16T05:30:16.396Z,1539667816.396 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-16T05:30:16.397Z,1539667816.397 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-16T05:30:16.397Z,1539667816.397 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-16T05:30:16.397Z,1539667816.397 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-16T05:30:16.427Z,1539667816.427 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-16T05:30:16.429Z,1539667816.429 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-16T05:30:16.429Z,1539667816.429 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-16T05:30:16.430Z,1539667816.430 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-16T05:30:16.430Z,1539667816.430 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T05:30:16.431Z,1539667816.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T05:30:16.431Z,1539667816.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T05:30:16.432Z,1539667816.432 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-16T05:30:16.432Z,1539667816.432 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-16T05:30:16.433Z,1539667816.433 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-16T05:30:16.460Z,1539667816.460 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-16T05:30:16.481Z,1539667816.481 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-10-16T05:30:16.481Z,1539667816.481 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-16T05:30:16.571Z,1539667816.571 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-16T05:30:16.572Z,1539667816.572 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-16T05:30:16.574Z,1539667816.574 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-16T05:30:16.613Z,1539667816.613 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-16T05:30:16.615Z,1539667816.615 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-16T05:30:16.637Z,1539667816.637 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-16T05:30:16.640Z,1539667816.640 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-10-16T05:30:16.651Z,1539667816.651 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-16T05:30:16.663Z,1539667816.663 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-16T05:30:16.691Z,1539667816.691 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-10-16T05:30:16.784Z,1539667816.784 [Aanderaa_O2](INFO): Start
2018-10-16T05:30:16.871Z,1539667816.871 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-16T05:30:16.959Z,1539667816.959 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T05:30:17.089Z,1539667817.089 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-16T05:30:17.107Z,1539667817.107 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-16T05:30:17.131Z,1539667817.131 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-16T05:30:17.161Z,1539667817.161 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-16T05:30:17.208Z,1539667817.208 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-16T05:30:17.211Z,1539667817.211 [MassServo](DEBUG): Initializing MassServo.
2018-10-16T05:30:17.273Z,1539667817.273 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-16T05:30:17.287Z,1539667817.287 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-16T05:30:17.341Z,1539667817.341 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-16T05:30:17.347Z,1539667817.347 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-16T05:30:17.627Z,1539667817.627 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:17.638Z,1539667817.638 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:30:17.647Z,1539667817.647 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T05:30:18.003Z,1539667818.003 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-16T05:30:18.003Z,1539667818.003 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-16T05:30:18.003Z,1539667818.003 [BuoyancyServo] Communications Fault, FailCount= 1
2018-10-16T05:30:18.003Z,1539667818.003 [BuoyancyServo](ERROR): Communications Fault
2018-10-16T05:30:18.306Z,1539667818.306 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-16T05:30:18.338Z,1539667818.338 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:18.356Z,1539667818.356 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T05:30:18.369Z,1539667818.369 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-16T05:30:18.370Z,1539667818.370 [BuoyancyServo](INFO): Powering down
2018-10-16T05:30:18.737Z,1539667818.737 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:18.742Z,1539667818.742 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T05:30:19.151Z,1539667819.151 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:19.151Z,1539667819.151 [Aanderaa_O2](INFO): Starting: can read until (59 bytes).
2018-10-16T05:30:19.157Z,1539667819.157 [Aanderaa_O2](INFO): Starting: read 89 bytes: 

2018-10-16T05:30:19.159Z,1539667819.159 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:30:19.187Z,1539667819.187 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T05:30:19.537Z,1539667819.537 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:19.576Z,1539667819.576 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T05:30:19.935Z,1539667819.935 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:19.987Z,1539667819.987 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T05:30:20.351Z,1539667820.351 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:20.370Z,1539667820.370 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T05:30:20.520Z,1539667820.520 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-16T05:30:20.520Z,1539667820.520 [BuoyancyServo] No Fault, FailCount= 1
2018-10-16T05:30:20.643Z,1539667820.643 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-10-16T05:30:20.705Z,1539667820.705 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-10-16T05:30:20.770Z,1539667820.770 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:20.848Z,1539667820.848 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-10-16T05:30:20.848Z,1539667820.848 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-10-16T05:30:20.849Z,1539667820.849 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-10-16T05:30:20.858Z,1539667820.858 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-16T05:30:20.859Z,1539667820.859 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-16T05:30:21.150Z,1539667821.150 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:21.162Z,1539667821.162 [Aanderaa_O2](INFO): Starting: can read until (5A bytes).
2018-10-16T05:30:21.179Z,1539667821.179 [Aanderaa_O2](INFO): Starting: read 90 bytes: 

2018-10-16T05:30:21.180Z,1539667821.180 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:30:21.554Z,1539667821.554 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:21.947Z,1539667821.947 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:22.400Z,1539667822.400 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:22.401Z,1539667822.401 [Aanderaa_O2](INFO): Starting: can read until (59 bytes).
2018-10-16T05:30:22.418Z,1539667822.418 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T05:30:22.418Z,1539667822.418 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-10-16T05:30:22.418Z,1539667822.418 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:30:22.620Z,1539667822.620 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:30:22.858Z,1539667822.858 [Aanderaa_O2](INFO): Stop
2018-10-16T05:30:22.867Z,1539667822.867 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:30:23.155Z,1539667823.155 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:30:23.544Z,1539667823.544 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:30:23.627Z,1539667823.627 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T05:30:23.627Z,1539667823.627 [Aanderaa_O2] No Fault, FailCount= 1
2018-10-16T05:30:23.944Z,1539667823.944 [Aanderaa_O2](INFO): Start
2018-10-16T05:30:24.345Z,1539667824.345 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:24.358Z,1539667824.358 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:30:24.745Z,1539667824.745 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:25.145Z,1539667825.145 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:25.545Z,1539667825.545 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:26.007Z,1539667826.007 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:26.346Z,1539667826.346 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:26.347Z,1539667826.347 [Aanderaa_O2](INFO): Starting: can read until (5A bytes).
2018-10-16T05:30:26.362Z,1539667826.362 [Aanderaa_O2](INFO): Starting: read 90 bytes: 

2018-10-16T05:30:26.363Z,1539667826.363 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:30:26.745Z,1539667826.745 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:27.151Z,1539667827.151 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:27.579Z,1539667827.579 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:27.991Z,1539667827.991 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:28.347Z,1539667828.347 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:28.347Z,1539667828.347 [Aanderaa_O2](INFO): Starting: can read until (5A bytes).
2018-10-16T05:30:28.358Z,1539667828.358 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T05:30:28.359Z,1539667828.359 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-10-16T05:30:28.359Z,1539667828.359 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:30:28.451Z,1539667828.451 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:30:28.745Z,1539667828.745 [Aanderaa_O2](INFO): Stop
2018-10-16T05:30:28.745Z,1539667828.745 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:30:29.149Z,1539667829.149 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:30:29.557Z,1539667829.557 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:30:29.632Z,1539667829.632 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T05:30:29.632Z,1539667829.632 [Aanderaa_O2] No Fault, FailCount= 2
2018-10-16T05:30:29.953Z,1539667829.953 [Aanderaa_O2](INFO): Start
2018-10-16T05:30:30.345Z,1539667830.345 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:30.358Z,1539667830.358 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:30:30.745Z,1539667830.745 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:31.145Z,1539667831.145 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:31.545Z,1539667831.545 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:31.958Z,1539667831.958 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:32.345Z,1539667832.345 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:32.345Z,1539667832.345 [Aanderaa_O2](INFO): Starting: can read until (5D bytes).
2018-10-16T05:30:32.349Z,1539667832.349 [Aanderaa_O2](INFO): Starting: read 93 bytes: 

2018-10-16T05:30:32.349Z,1539667832.349 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:30:32.746Z,1539667832.746 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:33.156Z,1539667833.156 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:33.545Z,1539667833.545 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:33.954Z,1539667833.954 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:34.345Z,1539667834.345 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:34.345Z,1539667834.345 [Aanderaa_O2](INFO): Starting: can read until (5A bytes).
2018-10-16T05:30:34.350Z,1539667834.350 [Aanderaa_O2](INFO): Starting: read 90 bytes: 

2018-10-16T05:30:34.350Z,1539667834.350 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:30:34.745Z,1539667834.745 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:35.161Z,1539667835.161 [Aanderaa_O2](INFO): Starting
2018-10-16T05:30:35.161Z,1539667835.161 [Aanderaa_O2](INFO): Starting: can read until (59 bytes).
2018-10-16T05:30:35.175Z,1539667835.175 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T05:30:35.175Z,1539667835.175 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-10-16T05:30:35.175Z,1539667835.175 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:30:35.251Z,1539667835.251 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:30:35.252Z,1539667835.252 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-10-16T05:30:35.545Z,1539667835.545 [Aanderaa_O2](INFO): Stop
2018-10-16T05:30:35.546Z,1539667835.546 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:30:35.945Z,1539667835.945 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:30:36.349Z,1539667836.349 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:30:45.520Z,1539667845.520 [NAL9602](INFO): Powering up NAL9602
2018-10-16T05:30:52.817Z,1539667852.817 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-16T05:30:52.821Z,1539667852.821 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-16T05:30:56.460Z,1539667856.460 [NAL9602](INFO): NAL9602 initialized
2018-10-16T05:31:04.349Z,1539667864.349 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): -0.013153
CHAN A2 (12V): -0.002138
CHAN A3 (5V): -0.002113
CHAN B0 (3.3V): 0.000273
CHAN B1 (3.15aV): -0.000758
CHAN B2 (3.15bV): -0.000326
CHAN B3 (GND): 0.000756
OPEN: -0.000401
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-16T05:31:46.515Z,1539667906.515 [SBIT](IMPORTANT): SBIT PASSED
2018-10-16T05:31:46.559Z,1539667906.559 [CommandLine](IMPORTANT): got command configSet list
2018-10-16T05:31:46.559Z,1539667906.559 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-16T05:31:46.560Z,1539667906.560 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=25 count;

2018-10-16T05:31:46.560Z,1539667906.560 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-10-16T05:31:46.560Z,1539667906.560 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 minute;

2018-10-16T05:31:46.560Z,1539667906.560 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2018-10-16T05:31:46.561Z,1539667906.561 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2018-10-16T05:31:46.561Z,1539667906.561 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-10-16T05:31:46.561Z,1539667906.561 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-10-16T05:31:46.561Z,1539667906.561 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-10-16T05:31:46.561Z,1539667906.561 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2018-10-16T05:31:46.561Z,1539667906.561 [CommandLine](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2018-10-16T05:31:46.561Z,1539667906.561 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-10-16T05:31:46.561Z,1539667906.561 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-10-16T05:31:46.897Z,1539667906.897 [MissionManager](IMPORTANT): Started mission Startup
2018-10-16T05:31:46.897Z,1539667906.897 [Startup] Running Loop=1
2018-10-16T05:31:46.897Z,1539667906.897 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-16T05:31:46.898Z,1539667906.898 [Startup:A.GoToSurface] Running Loop=1
2018-10-16T05:31:46.898Z,1539667906.898 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-16T05:31:46.898Z,1539667906.898 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-16T05:31:46.899Z,1539667906.899 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-16T05:31:46.899Z,1539667906.899 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-16T05:31:46.900Z,1539667906.900 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-16T05:31:46.900Z,1539667906.900 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-16T05:31:46.906Z,1539667906.906 [Startup:StartupSatComms] Running Loop=1
2018-10-16T05:31:46.907Z,1539667906.907 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-16T05:31:46.907Z,1539667906.907 [Startup:StartupSatComms:A] Running Loop=1
2018-10-16T05:31:47.278Z,1539667907.278 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-16T05:32:47.288Z,1539667967.288 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-16T05:31:46.9Z
2018-10-16T05:32:47.288Z,1539667967.288 [Startup:StartupSatComms:A] Stopped
2018-10-16T05:32:47.288Z,1539667967.288 [Startup:StartupSatComms:B] Running Loop=1
2018-10-16T05:32:47.677Z,1539667967.677 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-16T05:33:05.316Z,1539667985.316 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T05:33:05.317Z,1539667985.317 [Aanderaa_O2] No Fault, FailCount= 3
2018-10-16T05:33:05.660Z,1539667985.660 [Aanderaa_O2](INFO): Start
2018-10-16T05:33:06.097Z,1539667986.097 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:06.110Z,1539667986.110 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:33:06.461Z,1539667986.461 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:06.864Z,1539667986.864 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:07.268Z,1539667987.268 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:07.665Z,1539667987.665 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:08.113Z,1539667988.113 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:08.114Z,1539667988.114 [Aanderaa_O2](INFO): Starting: can read until (59 bytes).
2018-10-16T05:33:08.128Z,1539667988.128 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T05:33:08.128Z,1539667988.128 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-10-16T05:33:08.128Z,1539667988.128 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:33:08.193Z,1539667988.193 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:33:08.468Z,1539667988.468 [Aanderaa_O2](INFO): Stop
2018-10-16T05:33:08.469Z,1539667988.469 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:33:08.882Z,1539667988.882 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:33:09.283Z,1539667989.283 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:33:09.339Z,1539667989.339 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T05:33:09.339Z,1539667989.339 [Aanderaa_O2] No Fault, FailCount= 1
2018-10-16T05:33:09.687Z,1539667989.687 [Aanderaa_O2](INFO): Start
2018-10-16T05:33:10.121Z,1539667990.121 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:10.147Z,1539667990.147 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:33:10.463Z,1539667990.463 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:10.868Z,1539667990.868 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:11.266Z,1539667991.266 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:11.667Z,1539667991.667 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:12.056Z,1539667992.056 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:12.057Z,1539667992.057 [Aanderaa_O2](INFO): Starting: can read until (5A bytes).
2018-10-16T05:33:12.074Z,1539667992.074 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T05:33:12.075Z,1539667992.075 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-10-16T05:33:12.075Z,1539667992.075 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:33:12.142Z,1539667992.142 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:33:12.474Z,1539667992.474 [Aanderaa_O2](INFO): Stop
2018-10-16T05:33:12.475Z,1539667992.475 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:33:12.867Z,1539667992.867 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:33:13.256Z,1539667993.256 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:33:13.305Z,1539667993.305 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T05:33:13.305Z,1539667993.305 [Aanderaa_O2] No Fault, FailCount= 2
2018-10-16T05:33:13.656Z,1539667993.656 [Aanderaa_O2](INFO): Start
2018-10-16T05:33:14.087Z,1539667994.087 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:14.098Z,1539667994.098 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:33:14.500Z,1539667994.500 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:14.878Z,1539667994.878 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:15.271Z,1539667995.271 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:15.664Z,1539667995.664 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:16.078Z,1539667996.078 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:16.078Z,1539667996.078 [Aanderaa_O2](INFO): Starting: can read until (5E bytes).
2018-10-16T05:33:16.082Z,1539667996.082 [Aanderaa_O2](INFO): Starting: read 94 bytes: 

2018-10-16T05:33:16.082Z,1539667996.082 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:33:16.470Z,1539667996.470 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:16.478Z,1539667996.478 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T05:33:16.478Z,1539667996.478 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-16T05:33:16.478Z,1539667996.478 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T05:33:16.491Z,1539667996.491 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T05:33:16.879Z,1539667996.879 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:16.938Z,1539667996.938 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T05:33:16.938Z,1539667996.938 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-16T05:33:17.282Z,1539667997.282 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:17.289Z,1539667997.289 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T05:33:17.289Z,1539667997.289 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T05:33:17.290Z,1539667997.290 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T05:33:20.817Z,1539668000.817 [Aanderaa_O2](INFO): Starting
2018-10-16T05:33:20.818Z,1539668000.818 [Aanderaa_O2](INFO): Starting: can read until (5A bytes).
2018-10-16T05:33:20.847Z,1539668000.847 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T05:33:20.848Z,1539668000.848 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-10-16T05:33:20.848Z,1539668000.848 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:33:20.912Z,1539668000.912 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:33:20.913Z,1539668000.913 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-10-16T05:33:20.994Z,1539668000.994 [Aanderaa_O2](INFO): Stop
2018-10-16T05:33:20.994Z,1539668000.994 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:33:21.367Z,1539668001.367 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:33:21.728Z,1539668001.728 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004333
2018-10-16T05:33:21.772Z,1539668001.772 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:33:25.959Z,1539668005.959 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T052459/Courier0004.lzma
2018-10-16T05:33:26.744Z,1539668006.744 [DataOverHttps](INFO): Moved sent file to Logs/20181016T052459/Courier0004.lzma.bak
2018-10-16T05:33:26.744Z,1539668006.744 [DataOverHttps](INFO): SBD MOMSN=8715802
2018-10-16T05:33:36.319Z,1539668016.319 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20181016T052459/Express0005.lzma
2018-10-16T05:33:37.089Z,1539668017.089 [DataOverHttps](INFO): Moved sent file to Logs/20181016T052459/Express0005.lzma.bak
2018-10-16T05:33:37.090Z,1539668017.090 [DataOverHttps](INFO): SBD MOMSN=8715804
2018-10-16T05:33:47.017Z,1539668027.017 [DataOverHttps](INFO): Sending 999 bytes from file Logs/20181016T053008/Express0001.lzma
2018-10-16T05:33:47.424Z,1539668027.424 [Startup:StartupSatComms:B](INFO): Timed out from 2018-10-16T05:32:47.3Z
2018-10-16T05:33:47.424Z,1539668027.424 [Startup:StartupSatComms:B] Stopped
2018-10-16T05:33:47.424Z,1539668027.424 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-16T05:33:47.424Z,1539668027.424 [Startup:StartupSatComms] Stopped
2018-10-16T05:33:47.424Z,1539668027.424 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-16T05:33:47.425Z,1539668027.425 [Startup](INFO): Completed Startup
2018-10-16T05:33:47.425Z,1539668027.425 [MissionManager](INFO): Startup is completed.
2018-10-16T05:33:47.425Z,1539668027.425 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-16T05:33:47.426Z,1539668027.426 [Startup] Stopped
2018-10-16T05:33:47.426Z,1539668027.426 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-16T05:33:47.426Z,1539668027.426 [Startup:A.GoToSurface] Stopped
2018-10-16T05:33:47.426Z,1539668027.426 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-16T05:33:47.752Z,1539668027.752 [DataOverHttps](INFO): Moved sent file to Logs/20181016T053008/Express0001.lzma.bak
2018-10-16T05:33:47.752Z,1539668027.752 [DataOverHttps](INFO): SBD MOMSN=8715810
2018-10-16T05:33:47.901Z,1539668027.901 [MissionManager](IMPORTANT): Started mission Default
2018-10-16T05:33:47.902Z,1539668027.902 [Default] Running Loop=1
2018-10-16T05:33:47.915Z,1539668027.915 [Default](DEBUG): Aggregate::initialize Default
2018-10-16T05:33:47.916Z,1539668027.916 [Default:B.GoToSurface] Running Loop=1
2018-10-16T05:33:47.916Z,1539668027.916 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-16T05:33:47.917Z,1539668027.917 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-16T05:33:47.927Z,1539668027.927 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-16T05:33:47.928Z,1539668027.928 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-16T05:33:47.929Z,1539668027.929 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-16T05:33:47.930Z,1539668027.930 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-16T05:33:47.947Z,1539668027.947 [Default:A.Wait] Running Loop=1
2018-10-16T05:33:47.947Z,1539668027.947 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-16T05:34:00.996Z,1539668040.996 [Default:A.Wait](INFO): Done Waiting.
2018-10-16T05:34:00.996Z,1539668040.996 [Default:A.Wait] Stopped
2018-10-16T05:34:00.996Z,1539668040.996 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T05:34:01.396Z,1539668041.396 [Default:CheckIn] Running Loop=1
2018-10-16T05:34:01.397Z,1539668041.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T05:34:01.397Z,1539668041.397 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T05:34:01.823Z,1539668041.823 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-10-16T05:34:04.632Z,1539668044.632 [CommandLine](IMPORTANT): got command restart application
2018-10-16T05:34:05.638Z,1539668045.638 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-10-16T05:34:05.641Z,1539668045.641 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:34:05.642Z,1539668045.642 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:05.763Z,1539668045.763 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-16T05:34:05.763Z,1539668045.763 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:05.764Z,1539668045.764 [CommandLine](INFO): Join timeout helper Thread ID is 6292
2018-10-16T05:34:05.764Z,1539668045.764 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-16T05:34:05.766Z,1539668045.766 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:05.768Z,1539668045.768 [NavChartDb](INFO): Join timeout helper Thread ID is 6293
2018-10-16T05:34:06.131Z,1539668046.131 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:34:06.132Z,1539668046.132 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.147Z,1539668046.147 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-16T05:34:06.147Z,1539668046.147 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.147Z,1539668046.147 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6294
2018-10-16T05:34:06.443Z,1539668046.443 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:34:06.443Z,1539668046.443 [WetLabsBB2FL](INFO): Powering down
2018-10-16T05:34:06.444Z,1539668046.444 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.458Z,1539668046.458 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-10-16T05:34:06.458Z,1539668046.458 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.459Z,1539668046.459 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6295
2018-10-16T05:34:06.607Z,1539668046.607 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:34:06.607Z,1539668046.607 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-10-16T05:34:06.752Z,1539668046.752 [Rowe_600LCM](INFO): Powering down
2018-10-16T05:34:06.753Z,1539668046.753 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.762Z,1539668046.762 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-16T05:34:06.763Z,1539668046.763 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.763Z,1539668046.763 [Radio_Surface](INFO): Join timeout helper Thread ID is 6297
2018-10-16T05:34:06.815Z,1539668046.815 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:34:06.815Z,1539668046.815 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.827Z,1539668046.827 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-16T05:34:06.828Z,1539668046.828 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.828Z,1539668046.828 [logger](INFO): Join timeout helper Thread ID is 6298
2018-10-16T05:34:06.828Z,1539668046.828 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:34:06.829Z,1539668046.829 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.830Z,1539668046.830 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-16T05:34:06.830Z,1539668046.830 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.830Z,1539668046.830 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-16T05:34:06.831Z,1539668046.831 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:06.831Z,1539668046.831 [controlThread](INFO): Join timeout helper Thread ID is 6299
2018-10-16T05:34:06.946Z,1539668046.946 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:34:06.947Z,1539668046.947 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-16T05:34:06.948Z,1539668046.948 [NAL9602](INFO): Powering down
2018-10-16T05:34:06.949Z,1539668046.949 [PNI_TCM](INFO): Powering down
2018-10-16T05:34:07.035Z,1539668047.035 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:34:07.036Z,1539668047.036 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-16T05:34:07.037Z,1539668047.037 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-16T05:34:07.038Z,1539668047.038 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-16T05:34:07.038Z,1539668047.038 [MissionManager](INFO): Uninitializing Mission Default
2018-10-16T05:34:07.038Z,1539668047.038 [Default] Stopped
2018-10-16T05:34:07.039Z,1539668047.039 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-16T05:34:07.039Z,1539668047.039 [Default:B.GoToSurface] Stopped
2018-10-16T05:34:07.039Z,1539668047.039 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-16T05:34:07.039Z,1539668047.039 [Default:CheckIn] Stopped
2018-10-16T05:34:07.039Z,1539668047.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T05:34:07.039Z,1539668047.039 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T05:34:07.043Z,1539668047.043 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-16T05:34:07.043Z,1539668047.043 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-16T05:34:07.043Z,1539668047.043 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-16T05:34:07.044Z,1539668047.044 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-16T05:34:07.044Z,1539668047.044 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-16T05:34:07.044Z,1539668047.044 [BuoyancyServo](INFO): Powering down
2018-10-16T05:34:07.058Z,1539668047.058 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-16T05:34:07.059Z,1539668047.059 [ElevatorServo](INFO): Powering down
2018-10-16T05:34:07.060Z,1539668047.060 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-16T05:34:07.060Z,1539668047.060 [MassServo](INFO): Powering down
2018-10-16T05:34:07.061Z,1539668047.061 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-16T05:34:07.061Z,1539668047.061 [RudderServo](INFO): Powering down
2018-10-16T05:34:07.062Z,1539668047.062 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-16T05:34:07.062Z,1539668047.062 [ThrusterServo](INFO): Powering down
2018-10-16T05:34:07.063Z,1539668047.063 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-16T05:34:07.063Z,1539668047.063 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-16T05:34:07.064Z,1539668047.064 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-16T05:34:07.064Z,1539668047.064 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:07.122Z,1539668047.122 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:07.129Z,1539668047.129 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:07.171Z,1539668047.171 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:07.201Z,1539668047.201 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:34:07.259Z,1539668047.259 [logger ThreadHandler](INFO): Thread cancelled.