2018-05-15T17:57:22.234Z,1526407042.234 [Supervisor](DEBUG): Initializing supervisor.
2018-05-15T17:57:22.237Z,1526407042.237 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-15T17:57:22.238Z,1526407042.238 [SyncHandler](INFO): Protected caller Thread ID is 7497
2018-05-15T17:57:22.238Z,1526407042.238 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-15T17:57:22.239Z,1526407042.239 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-15T17:57:22.239Z,1526407042.239 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7498
2018-05-15T17:57:22.242Z,1526407042.242 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-15T17:57:22.254Z,1526407042.254 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-15T17:57:22.254Z,1526407042.254 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-15T17:57:22.255Z,1526407042.255 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7499
2018-05-15T17:57:22.256Z,1526407042.256 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-15T17:57:22.257Z,1526407042.257 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-15T17:57:22.257Z,1526407042.257 [logger ThreadHandler](INFO): Protected caller Thread ID is 7500
2018-05-15T17:57:22.259Z,1526407042.259 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-15T17:57:22.259Z,1526407042.259 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-15T17:57:22.261Z,1526407042.261 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-15T17:57:22.354Z,1526407042.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-15T17:57:22.355Z,1526407042.355 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-15T17:57:22.451Z,1526407042.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-15T17:57:22.452Z,1526407042.452 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-15T17:57:22.640Z,1526407042.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-15T17:57:22.641Z,1526407042.641 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-15T17:57:22.938Z,1526407042.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-15T17:57:22.939Z,1526407042.939 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-15T17:57:23.251Z,1526407043.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-15T17:57:23.252Z,1526407043.252 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-15T17:57:23.685Z,1526407043.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-15T17:57:23.686Z,1526407043.686 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-15T17:57:24.032Z,1526407044.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-15T17:57:24.033Z,1526407044.033 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-15T17:57:24.451Z,1526407044.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-15T17:57:24.452Z,1526407044.452 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-15T17:57:24.530Z,1526407044.530 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-15T17:57:24.811Z,1526407044.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-15T17:57:24.812Z,1526407044.812 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-15T17:57:24.949Z,1526407044.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-15T17:57:24.950Z,1526407044.950 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-15T17:57:25.150Z,1526407045.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-15T17:57:25.151Z,1526407045.151 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-15T17:57:25.423Z,1526407045.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-15T17:57:25.423Z,1526407045.423 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-15T17:57:25.520Z,1526407045.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-15T17:57:25.521Z,1526407045.521 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-15T17:57:25.655Z,1526407045.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-15T17:57:25.657Z,1526407045.657 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-05-15T17:57:25.658Z,1526407045.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-05-15T17:57:25.884Z,1526407045.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-15T17:57:25.885Z,1526407045.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-05-15T17:57:25.960Z,1526407045.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-05-15T17:57:26.096Z,1526407046.096 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-05-15T17:57:26.185Z,1526407046.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-05-15T17:57:26.268Z,1526407046.268 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-05-15T17:57:26.400Z,1526407046.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-05-15T17:57:26.567Z,1526407046.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-05-15T17:57:26.661Z,1526407046.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-05-15T17:57:26.750Z,1526407046.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-05-15T17:57:26.844Z,1526407046.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-05-15T17:57:26.925Z,1526407046.925 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-05-15T17:57:26.926Z,1526407046.926 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-15T17:57:26.939Z,1526407046.939 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-15T17:57:27.372Z,1526407047.372 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-15T17:57:27.373Z,1526407047.373 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-15T17:57:27.420Z,1526407047.420 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-15T17:57:27.420Z,1526407047.420 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-15T17:57:27.461Z,1526407047.461 [DepthRateCalculator] Loaded
2018-05-15T17:57:27.461Z,1526407047.461 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-15T17:57:27.466Z,1526407047.466 [PitchRateCalculator] Loaded
2018-05-15T17:57:27.467Z,1526407047.467 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-15T17:57:27.482Z,1526407047.482 [SpeedCalculator] Loaded
2018-05-15T17:57:27.482Z,1526407047.482 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-15T17:57:27.502Z,1526407047.502 [TempGradientCalculator] Loaded
2018-05-15T17:57:27.502Z,1526407047.502 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-15T17:57:27.517Z,1526407047.517 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-15T17:57:27.518Z,1526407047.518 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-15T17:57:27.523Z,1526407047.523 [YawRateCalculator] Loaded
2018-05-15T17:57:27.523Z,1526407047.523 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-15T17:57:27.579Z,1526407047.579 [ElevatorOffsetCalculator] Loaded
2018-05-15T17:57:27.579Z,1526407047.579 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-15T17:57:27.580Z,1526407047.580 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-15T17:57:27.580Z,1526407047.580 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-15T17:57:27.648Z,1526407047.648 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-15T17:57:27.669Z,1526407047.669 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-15T17:57:28.028Z,1526407048.028 [AHRS_M2] Loaded
2018-05-15T17:57:28.028Z,1526407048.028 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-05-15T17:57:28.070Z,1526407048.070 [AcousticModem_Benthos_ATM900] Loaded
2018-05-15T17:57:28.070Z,1526407048.070 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-05-15T17:57:28.137Z,1526407048.137 [DataOverHttps] Loaded
2018-05-15T17:57:28.137Z,1526407048.137 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-15T17:57:28.150Z,1526407048.150 [Depth_Keller] Loaded
2018-05-15T17:57:28.150Z,1526407048.150 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-15T17:57:28.150Z,1526407048.150 [Module Loader](CRITICAL): Could not find element GobyModem.loadAtStartup
2018-05-15T17:57:28.243Z,1526407048.243 [NAL9602] Loaded
2018-05-15T17:57:28.243Z,1526407048.243 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-15T17:57:28.258Z,1526407048.258 [Onboard] Loaded
2018-05-15T17:57:28.258Z,1526407048.258 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-15T17:57:28.268Z,1526407048.268 [Radio_Surface] Loaded
2018-05-15T17:57:28.268Z,1526407048.268 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-15T17:57:28.269Z,1526407048.269 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B74E0
2018-05-15T17:57:28.270Z,1526407048.270 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7580
2018-05-15T17:57:28.270Z,1526407048.270 [Module Loader](CRITICAL): Could not find element RDI_Pathfinder.loadAtStartup
2018-05-15T17:57:29.953Z,1526407049.953 [BPC1] Loaded
2018-05-15T17:57:29.953Z,1526407049.953 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-15T17:57:29.954Z,1526407049.954 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-15T17:57:29.954Z,1526407049.954 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-15T17:57:30.078Z,1526407050.078 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-15T17:57:30.100Z,1526407050.100 [SBIT] Loaded
2018-05-15T17:57:30.100Z,1526407050.100 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-15T17:57:30.101Z,1526407050.101 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-15T17:57:30.111Z,1526407050.111 [IBIT] Loaded
2018-05-15T17:57:30.112Z,1526407050.112 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-15T17:57:30.115Z,1526407050.115 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-15T17:57:30.259Z,1526407050.259 [CBIT] Loaded
2018-05-15T17:57:30.259Z,1526407050.259 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-15T17:57:30.259Z,1526407050.259 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-15T17:57:30.260Z,1526407050.260 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-15T17:57:30.428Z,1526407050.428 [CTD_Seabird] Loaded
2018-05-15T17:57:30.429Z,1526407050.429 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-15T17:57:30.430Z,1526407050.430 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408774E0
2018-05-15T17:57:30.430Z,1526407050.430 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7581
2018-05-15T17:57:30.446Z,1526407050.446 [ESPComponent] Loaded
2018-05-15T17:57:30.447Z,1526407050.447 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-15T17:57:30.460Z,1526407050.460 [PAR_Licor] Loaded
2018-05-15T17:57:30.460Z,1526407050.460 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-15T17:57:30.461Z,1526407050.461 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-15T17:57:30.462Z,1526407050.462 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-15T17:57:30.690Z,1526407050.690 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-15T17:57:30.691Z,1526407050.691 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-15T17:57:30.756Z,1526407050.756 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-15T17:57:30.848Z,1526407050.848 [VerticalControl] Loaded
2018-05-15T17:57:30.848Z,1526407050.848 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-15T17:57:30.849Z,1526407050.849 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-15T17:57:30.907Z,1526407050.907 [HorizontalControl] Loaded
2018-05-15T17:57:30.907Z,1526407050.907 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-15T17:57:30.908Z,1526407050.908 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-15T17:57:30.913Z,1526407050.913 [SpeedControl] Loaded
2018-05-15T17:57:30.913Z,1526407050.913 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-15T17:57:30.914Z,1526407050.914 [LoopControl](DEBUG): Construct LoopControl.
2018-05-15T17:57:30.914Z,1526407050.914 [LoopControl] Loaded
2018-05-15T17:57:30.914Z,1526407050.914 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-15T17:57:30.915Z,1526407050.915 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-15T17:57:30.915Z,1526407050.915 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-15T17:57:30.995Z,1526407050.995 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-15T17:57:30.995Z,1526407050.995 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-15T17:57:30.995Z,1526407050.995 [StratificationFrontDetector] Loaded
2018-05-15T17:57:30.996Z,1526407050.996 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-15T17:57:30.996Z,1526407050.996 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-15T17:57:30.997Z,1526407050.997 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-15T17:57:31.090Z,1526407051.090 [BuoyancyServo] Loaded
2018-05-15T17:57:31.090Z,1526407051.090 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-15T17:57:31.102Z,1526407051.102 [ElevatorServo] Loaded
2018-05-15T17:57:31.102Z,1526407051.102 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-15T17:57:31.112Z,1526407051.112 [MassServo] Loaded
2018-05-15T17:57:31.113Z,1526407051.113 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-15T17:57:31.123Z,1526407051.123 [RudderServo] Loaded
2018-05-15T17:57:31.124Z,1526407051.124 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-15T17:57:31.134Z,1526407051.134 [ThrusterServo] Loaded
2018-05-15T17:57:31.134Z,1526407051.134 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-15T17:57:31.135Z,1526407051.135 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-15T17:57:31.135Z,1526407051.135 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-15T17:57:31.229Z,1526407051.229 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-05-15T17:57:31.229Z,1526407051.229 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-05-15T17:57:31.281Z,1526407051.281 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-15T17:57:31.282Z,1526407051.282 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-15T17:57:31.298Z,1526407051.298 [NavChart] Loaded
2018-05-15T17:57:31.298Z,1526407051.298 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-15T17:57:31.302Z,1526407051.302 [UniversalFixResidualReporter] Loaded
2018-05-15T17:57:31.302Z,1526407051.302 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-15T17:57:31.302Z,1526407051.302 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-15T17:57:31.306Z,1526407051.306 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-15T17:57:31.307Z,1526407051.307 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-15T17:57:31.314Z,1526407051.314 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-15T17:57:31.315Z,1526407051.315 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0
2018-05-15T17:57:31.315Z,1526407051.315 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7582
2018-05-15T17:57:31.320Z,1526407051.320 [Supervisor](INFO): Main Thread ID is 7496
2018-05-15T17:57:31.320Z,1526407051.320 [Supervisor](DEBUG): Running supervisor.
2018-05-15T17:57:31.321Z,1526407051.321 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7583
2018-05-15T17:57:31.323Z,1526407051.323 [controlThread ThreadHandler](INFO): Handler Thread ID is 7584
2018-05-15T17:57:31.324Z,1526407051.324 [controlThread](DEBUG): Initializing ControlThread
2018-05-15T17:57:31.325Z,1526407051.325 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-15T17:57:31.325Z,1526407051.325 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-15T17:57:31.326Z,1526407051.326 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-15T17:57:31.326Z,1526407051.326 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-15T17:57:31.326Z,1526407051.326 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-15T17:57:31.327Z,1526407051.327 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-15T17:57:31.328Z,1526407051.328 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-15T17:57:31.334Z,1526407051.334 [SBIT](INFO): Initialize SBIT Component.
2018-05-15T17:57:31.334Z,1526407051.334 [SBIT](IMPORTANT): git: 2018-03-26-63-g035c652
2018-05-15T17:57:31.334Z,1526407051.334 [SBIT](INFO): git hash: 035c652db7f27c72339a0dc8ffe46f005dcbcd78
2018-05-15T17:57:31.335Z,1526407051.335 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-15T17:57:31.336Z,1526407051.336 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #16 PREEMPT Thu Apr 12 16:20:47 PDT 2018
2018-05-15T17:57:31.337Z,1526407051.337 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2018-05-15T17:57:31.337Z,1526407051.337 [IBIT](INFO): Initialize IBIT Component.
2018-05-15T17:57:31.338Z,1526407051.338 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-15T17:57:31.338Z,1526407051.338 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-05-15T17:57:31.339Z,1526407051.339 [logger ThreadHandler](INFO): Handler Thread ID is 7585
2018-05-15T17:57:31.357Z,1526407051.357 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7586
2018-05-15T17:57:31.363Z,1526407051.363 [Radio_Surface](INFO): Powering up
2018-05-15T17:57:31.369Z,1526407051.369 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7587
2018-05-15T17:57:31.370Z,1526407051.370 [CTD_Seabird](INFO): Initializing
2018-05-15T17:57:31.370Z,1526407051.370 [CTD_Seabird](INFO): Checking LCM
2018-05-15T17:57:31.379Z,1526407051.379 [CTD_Seabird](INFO): LCM OK
2018-05-15T17:57:31.379Z,1526407051.379 [CTD_Seabird](INFO): Powering up
2018-05-15T17:57:31.385Z,1526407051.385 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7588
2018-05-15T17:57:31.389Z,1526407051.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-15T17:57:31.389Z,1526407051.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-15T17:57:31.389Z,1526407051.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-15T17:57:31.389Z,1526407051.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-15T17:57:31.389Z,1526407051.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-15T17:57:31.390Z,1526407051.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-15T17:57:31.390Z,1526407051.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-15T17:57:31.390Z,1526407051.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-15T17:57:31.390Z,1526407051.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-15T17:57:31.390Z,1526407051.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-15T17:57:31.391Z,1526407051.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-15T17:57:31.391Z,1526407051.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-15T17:57:31.391Z,1526407051.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-15T17:57:31.391Z,1526407051.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-15T17:57:31.391Z,1526407051.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-15T17:57:31.392Z,1526407051.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-15T17:57:31.453Z,1526407051.453 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-15T17:57:31.455Z,1526407051.455 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-15T17:57:31.455Z,1526407051.455 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-15T17:57:31.456Z,1526407051.456 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-15T17:57:31.458Z,1526407051.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-05-15T17:57:31.458Z,1526407051.458 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-05-15T17:57:31.459Z,1526407051.459 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-05-15T17:57:31.459Z,1526407051.459 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-15T17:57:31.460Z,1526407051.460 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-15T17:57:31.460Z,1526407051.460 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-15T17:57:31.460Z,1526407051.460 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-15T17:57:31.461Z,1526407051.461 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-15T17:57:31.462Z,1526407051.462 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-15T17:57:31.493Z,1526407051.493 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-15T17:57:31.537Z,1526407051.537 [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-05-15T17:57:31.538Z,1526407051.538 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-15T17:57:31.607Z,1526407051.607 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-15T17:57:31.608Z,1526407051.608 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-15T17:57:31.610Z,1526407051.610 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-15T17:57:31.654Z,1526407051.654 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-15T17:57:31.663Z,1526407051.663 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-15T17:57:31.693Z,1526407051.693 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-15T17:57:31.696Z,1526407051.696 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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-05-15T17:57:31.701Z,1526407051.701 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-15T17:57:31.717Z,1526407051.717 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-15T17:57:31.789Z,1526407051.789 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-05-15T17:57:31.789Z,1526407051.789 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-05-15T17:57:31.815Z,1526407051.815 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-15T17:57:32.078Z,1526407052.078 [ESPComponent](INFO): powering down ESP
2018-05-15T17:57:32.546Z,1526407052.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:32.574Z,1526407052.574 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:32.685Z,1526407052.685 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-15T17:57:32.693Z,1526407052.693 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-15T17:57:32.723Z,1526407052.723 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-15T17:57:32.729Z,1526407052.729 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-15T17:57:32.735Z,1526407052.735 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-15T17:57:32.741Z,1526407052.741 [MassServo](DEBUG): Initializing MassServo.
2018-05-15T17:57:32.747Z,1526407052.747 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-15T17:57:32.753Z,1526407052.753 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-15T17:57:32.758Z,1526407052.758 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-15T17:57:32.765Z,1526407052.765 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-15T17:57:32.795Z,1526407052.795 [CommandLine](FAULT): Scheduling is paused
2018-05-15T17:57:32.795Z,1526407052.795 [CBIT](INFO): Critical error at 20180515T175731
2018-05-15T17:57:32.796Z,1526407052.796 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-05-15T17:57:32.983Z,1526407052.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:32.984Z,1526407052.984 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:36.883Z,1526407056.883 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T17:57:36.996Z,1526407056.996 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-05-15T17:57:37.070Z,1526407057.070 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-15T17:57:37.457Z,1526407057.457 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T17:57:37.457Z,1526407057.457 [CTD_Seabird](INFO): Powering down
2018-05-15T17:57:41.931Z,1526407061.931 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:41.932Z,1526407061.932 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:42.265Z,1526407062.265 [AHRS_M2](FAULT): Failed to initialize
2018-05-15T17:57:42.265Z,1526407062.265 [AHRS_M2] Communications Fault, FailCount= 1
2018-05-15T17:57:42.265Z,1526407062.265 [AHRS_M2](ERROR): Communications Fault
2018-05-15T17:57:42.266Z,1526407062.266 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-05-15T17:57:42.268Z,1526407062.268 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-05-15T17:57:42.268Z,1526407062.268 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-05-15T17:57:42.269Z,1526407062.269 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-05-15T17:57:42.270Z,1526407062.270 [AcousticModem_Benthos_ATM900](DEBUG): Apr  6 2000 13:45:22 
2018-05-15T17:57:42.270Z,1526407062.270 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-05-15T17:57:42.322Z,1526407062.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:42.323Z,1526407062.323 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:42.343Z,1526407062.343 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-05-15T17:57:42.677Z,1526407062.677 [AHRS_M2](INFO): Powering down
2018-05-15T17:57:42.809Z,1526407062.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:42.810Z,1526407062.810 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:43.041Z,1526407063.041 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-05-15T17:57:43.048Z,1526407063.048 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004216
2018-05-15T17:57:43.105Z,1526407063.105 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:43.106Z,1526407063.106 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:43.441Z,1526407063.441 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-05-15T17:57:43.442Z,1526407063.442 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-05-15T17:57:43.442Z,1526407063.442 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-05-15T17:57:43.489Z,1526407063.489 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:43.490Z,1526407063.490 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:43.842Z,1526407063.842 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-05-15T17:57:43.903Z,1526407063.903 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:43.921Z,1526407063.921 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:44.241Z,1526407064.241 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-05-15T17:57:44.242Z,1526407064.242 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-05-15T17:57:44.292Z,1526407064.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:44.301Z,1526407064.301 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:44.469Z,1526407064.469 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-05-15T17:57:44.469Z,1526407064.469 [AHRS_M2] No Fault, FailCount= 1
2018-05-15T17:57:44.641Z,1526407064.641 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-15T17:57:44.650Z,1526407064.650 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-05-15T17:57:44.689Z,1526407064.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:44.690Z,1526407064.690 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:45.094Z,1526407065.094 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:45.095Z,1526407065.095 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:45.599Z,1526407065.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:45.609Z,1526407065.609 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:45.962Z,1526407065.962 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:45.963Z,1526407065.963 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:46.385Z,1526407066.385 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:46.386Z,1526407066.386 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:46.775Z,1526407066.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:46.776Z,1526407066.776 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:47.165Z,1526407067.165 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:47.166Z,1526407067.166 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:47.549Z,1526407067.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:47.550Z,1526407067.550 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:47.953Z,1526407067.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:47.954Z,1526407067.954 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:48.358Z,1526407068.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:48.359Z,1526407068.359 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:48.777Z,1526407068.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:57:48.778Z,1526407068.778 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T17:58:03.906Z,1526407083.906 [NAL9602](INFO): No messages in MT queue
2018-05-15T17:58:23.983Z,1526407103.983 [SBIT](IMPORTANT): Beginning Startup BIT
2018-05-15T17:58:23.996Z,1526407103.996 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-15T17:58:32.518Z,1526407112.518 [CommandLine](IMPORTANT): got command failComponent
2018-05-15T17:58:32.518Z,1526407112.518 [CommandLine](IMPORTANT): Failed components:
2018-05-15T17:58:32.518Z,1526407112.518 [CommandLine](IMPORTANT): No failed Components.
2018-05-15T17:58:37.321Z,1526407117.321 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007815
CHAN A1 (24V): -0.012134
CHAN A2 (12V): -0.005679
CHAN A3 (5V): -0.002789
CHAN B0 (3.3V): -0.001543
CHAN B1 (3.15aV): -0.001162
CHAN B2 (3.15bV): -0.000987
CHAN B3 (GND): -0.000009
OPEN: -0.000983
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-15T17:59:00.058Z,1526407140.058 [CommandLine](IMPORTANT): got command report mod AHRS_M2.platform_pitch_angle
2018-05-15T17:59:00.161Z,1526407140.161 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553616 rad
2018-05-15T17:59:00.549Z,1526407140.549 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553745 rad
2018-05-15T17:59:00.953Z,1526407140.953 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553671 rad
2018-05-15T17:59:01.360Z,1526407141.360 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553536 rad
2018-05-15T17:59:01.740Z,1526407141.740 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553588 rad
2018-05-15T17:59:02.169Z,1526407142.169 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553571 rad
2018-05-15T17:59:02.560Z,1526407142.560 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553623 rad
2018-05-15T17:59:02.942Z,1526407142.942 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553478 rad
2018-05-15T17:59:03.347Z,1526407143.347 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553488 rad
2018-05-15T17:59:03.778Z,1526407143.778 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553512 rad
2018-05-15T17:59:04.182Z,1526407144.182 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553668 rad
2018-05-15T17:59:04.558Z,1526407144.558 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553724 rad
2018-05-15T17:59:04.957Z,1526407144.957 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553664 rad
2018-05-15T17:59:05.502Z,1526407145.502 [Reporter](INFO): AHRS_M2.platform_pitch_angle -1.553602 rad
2018-05-15T17:59:05.669Z,1526407145.669 [CommandLine](IMPORTANT): got command report clear
2018-05-15T17:59:17.456Z,1526407157.456 [SBIT](IMPORTANT): SBIT PASSED
2018-05-15T17:59:17.490Z,1526407157.490 [CommandLine](IMPORTANT): got command configSet list
2018-05-15T17:59:17.490Z,1526407157.490 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-05-15T17:59:17.491Z,1526407157.491 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-05-15T17:59:17.491Z,1526407157.491 [CommandLine](IMPORTANT): CBIT.abortDepth=300 meter;

2018-05-15T17:59:17.491Z,1526407157.491 [CommandLine](IMPORTANT): CBIT.gf24Offset=107 microampere;

2018-05-15T17:59:17.491Z,1526407157.491 [CommandLine](IMPORTANT): CBIT.stopDepth=285 meter;

2018-05-15T17:59:17.491Z,1526407157.491 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2018-05-15T17:59:17.491Z,1526407157.491 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2018-05-15T17:59:17.491Z,1526407157.491 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2018-05-15T17:59:17.492Z,1526407157.492 [CommandLine](IMPORTANT): NAL9602.simulateHardware=1 bool;

2018-05-15T17:59:17.492Z,1526407157.492 [CommandLine](IMPORTANT): Normal all CTD_Seabird.sea_water_salinity;

2018-05-15T17:59:17.492Z,1526407157.492 [CommandLine](IMPORTANT): Normal all CTD_Seabird.sea_water_temperature;

2018-05-15T17:59:17.492Z,1526407157.492 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-05-15T17:59:17.492Z,1526407157.492 [CommandLine](IMPORTANT): RudderServo.offsetAngle=0.5 degree;

2018-05-15T17:59:17.492Z,1526407157.492 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=130 cubic_centimeter;

2018-05-15T17:59:17.492Z,1526407157.492 [CommandLine](IMPORTANT): VerticalControl.massDefault=8 millimeter;

2018-05-15T17:59:17.493Z,1526407157.493 [CommandLine](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2018-05-15T17:59:17.851Z,1526407157.851 [MissionManager](IMPORTANT): Started mission Startup
2018-05-15T17:59:17.851Z,1526407157.851 [Startup] Running Loop=1
2018-05-15T17:59:17.851Z,1526407157.851 [Startup](DEBUG): Aggregate::initialize Startup
2018-05-15T17:59:17.851Z,1526407157.851 [Startup:A.GoToSurface] Running Loop=1
2018-05-15T17:59:17.852Z,1526407157.852 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-15T17:59:17.852Z,1526407157.852 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-15T17:59:17.853Z,1526407157.853 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-15T17:59:17.853Z,1526407157.853 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-15T17:59:17.854Z,1526407157.854 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-15T17:59:17.854Z,1526407157.854 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-15T17:59:17.860Z,1526407157.860 [Startup:StartupSatComms] Running Loop=1
2018-05-15T17:59:17.861Z,1526407157.861 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-05-15T17:59:17.861Z,1526407157.861 [Startup:StartupSatComms:A] Running Loop=1
2018-05-15T17:59:18.258Z,1526407158.258 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-05-15T18:00:18.214Z,1526407218.214 [Startup:StartupSatComms:A](INFO): Timed out from 2018-05-15T17:59:17.9Z
2018-05-15T18:00:18.214Z,1526407218.214 [Startup:StartupSatComms:A] Stopped
2018-05-15T18:00:18.214Z,1526407218.214 [Startup:StartupSatComms:B] Running Loop=1
2018-05-15T18:00:18.614Z,1526407218.614 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-15T18:00:22.552Z,1526407222.552 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180515T050444/Courier0004.lzma
2018-05-15T18:00:23.336Z,1526407223.336 [DataOverHttps](INFO): Moved sent file to Logs/20180515T050444/Courier0004.lzma.bak
2018-05-15T18:00:23.336Z,1526407223.336 [DataOverHttps](INFO): SBD MOMSN=8282347
2018-05-15T18:00:32.863Z,1526407232.863 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-15T18:00:32.864Z,1526407232.864 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-05-15T18:00:32.864Z,1526407232.864 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-05-15T18:00:32.866Z,1526407232.866 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-15T18:00:32.866Z,1526407232.866 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-05-15T18:00:32.866Z,1526407232.866 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-15T18:00:32.878Z,1526407232.878 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-05-15T18:00:32.878Z,1526407232.878 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-15T18:00:32.891Z,1526407232.891 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20180515T175722/Courier0000.lzma
2018-05-15T18:00:32.984Z,1526407232.984 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-05-15T18:00:32.984Z,1526407232.984 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-05-15T18:00:32.987Z,1526407232.987 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-15T18:00:32.987Z,1526407232.987 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-05-15T18:00:33.339Z,1526407233.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-05-15T18:00:33.340Z,1526407233.340 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-05-15T18:00:33.340Z,1526407233.340 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-05-15T18:00:33.341Z,1526407233.341 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-15T18:00:33.341Z,1526407233.341 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-15T18:00:33.342Z,1526407233.342 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-15T18:00:33.689Z,1526407233.689 [DataOverHttps](INFO): Moved sent file to Logs/20180515T175722/Courier0000.lzma.bak
2018-05-15T18:00:33.689Z,1526407233.689 [DataOverHttps](INFO): SBD MOMSN=8282350
2018-05-15T18:00:33.740Z,1526407233.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-05-15T18:00:34.750Z,1526407234.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.9 s old, using for 20.0 s.
2018-05-15T18:00:34.887Z,1526407234.887 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-05-15T18:00:37.933Z,1526407237.933 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2018-05-15T18:00:38.035Z,1526407238.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-05-15T18:00:38.397Z,1526407238.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2018-05-15T18:00:38.795Z,1526407238.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2018-05-15T18:00:39.198Z,1526407239.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2018-05-15T18:00:39.600Z,1526407239.600 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2018-05-15T18:00:42.685Z,1526407242.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.8 s old, using for 20.0 s.
2018-05-15T18:00:42.711Z,1526407242.711 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20180515T045404/Express0011.lzma
2018-05-15T18:00:42.803Z,1526407242.803 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s.
2018-05-15T18:00:43.163Z,1526407243.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.3 s old, using for 20.0 s.
2018-05-15T18:00:43.509Z,1526407243.509 [DataOverHttps](INFO): Moved sent file to Logs/20180515T045404/Express0011.lzma.bak
2018-05-15T18:00:43.509Z,1526407243.509 [DataOverHttps](INFO): SBD MOMSN=8282356
2018-05-15T18:00:43.568Z,1526407243.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.7 s old, using for 20.0 s.
2018-05-15T18:00:44.547Z,1526407244.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-05-15T18:00:44.646Z,1526407244.646 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2018-05-15T18:00:44.886Z,1526407244.886 [CommandLine](IMPORTANT): got command restart application
2018-05-15T18:00:45.896Z,1526407245.896 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:00:45.896Z,1526407245.896 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.045Z,1526407246.045 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-05-15T18:00:46.045Z,1526407246.045 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.045Z,1526407246.045 [CommandLine](INFO): Join timeout helper Thread ID is 7631
2018-05-15T18:00:46.057Z,1526407246.057 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-05-15T18:00:46.057Z,1526407246.057 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.057Z,1526407246.057 [NavChartDb](INFO): Join timeout helper Thread ID is 7632
2018-05-15T18:00:46.097Z,1526407246.097 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:00:46.097Z,1526407246.097 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.109Z,1526407246.109 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-05-15T18:00:46.109Z,1526407246.109 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.109Z,1526407246.109 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7633
2018-05-15T18:00:46.261Z,1526407246.261 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:00:46.416Z,1526407246.416 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:00:46.416Z,1526407246.416 [CTD_Seabird](INFO): Powering down
2018-05-15T18:00:46.417Z,1526407246.417 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.421Z,1526407246.421 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-05-15T18:00:46.421Z,1526407246.421 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.421Z,1526407246.421 [Radio_Surface](INFO): Join timeout helper Thread ID is 7635
2018-05-15T18:00:46.585Z,1526407246.585 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:00:46.586Z,1526407246.586 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.601Z,1526407246.601 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-05-15T18:00:46.601Z,1526407246.601 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.601Z,1526407246.601 [logger](INFO): Join timeout helper Thread ID is 7636
2018-05-15T18:00:46.629Z,1526407246.629 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:00:46.629Z,1526407246.629 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.638Z,1526407246.638 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-05-15T18:00:46.638Z,1526407246.638 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.638Z,1526407246.638 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-05-15T18:00:46.638Z,1526407246.638 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:46.639Z,1526407246.639 [controlThread](INFO): Join timeout helper Thread ID is 7637
2018-05-15T18:00:47.793Z,1526407247.793 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-05-15T18:00:47.809Z,1526407247.809 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:00:47.809Z,1526407247.809 [controlThread](DEBUG): Uninitializing ControlThread
2018-05-15T18:00:47.854Z,1526407247.854 [AHRS_M2](INFO): Powering down
2018-05-15T18:00:47.925Z,1526407247.925 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-05-15T18:00:48.048Z,1526407248.048 [NAL9602](INFO): Powering down
2018-05-15T18:00:48.051Z,1526407248.051 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-15T18:00:48.052Z,1526407248.052 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-05-15T18:00:48.053Z,1526407248.053 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-05-15T18:00:48.054Z,1526407248.054 [MissionManager](INFO): Uninitializing Mission Default
2018-05-15T18:00:48.056Z,1526407248.056 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-15T18:00:48.056Z,1526407248.056 [Startup] Stopped
2018-05-15T18:00:48.057Z,1526407248.057 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-05-15T18:00:48.057Z,1526407248.057 [Startup:A.GoToSurface] Stopped
2018-05-15T18:00:48.057Z,1526407248.057 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-15T18:00:48.057Z,1526407248.057 [Startup:StartupSatComms] Stopped
2018-05-15T18:00:48.057Z,1526407248.057 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-05-15T18:00:48.057Z,1526407248.057 [Startup:StartupSatComms:B] Stopped
2018-05-15T18:00:48.059Z,1526407248.059 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-05-15T18:00:48.059Z,1526407248.059 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-05-15T18:00:48.059Z,1526407248.059 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-05-15T18:00:48.060Z,1526407248.060 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-05-15T18:00:48.060Z,1526407248.060 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-15T18:00:48.060Z,1526407248.060 [BuoyancyServo](INFO): Powering down
2018-05-15T18:00:48.073Z,1526407248.073 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-05-15T18:00:48.073Z,1526407248.073 [ElevatorServo](INFO): Powering down
2018-05-15T18:00:48.074Z,1526407248.074 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-05-15T18:00:48.074Z,1526407248.074 [MassServo](INFO): Powering down
2018-05-15T18:00:48.075Z,1526407248.075 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-05-15T18:00:48.075Z,1526407248.075 [RudderServo](INFO): Powering down
2018-05-15T18:00:48.076Z,1526407248.076 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-15T18:00:48.076Z,1526407248.076 [ThrusterServo](INFO): Powering down
2018-05-15T18:00:48.077Z,1526407248.077 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-05-15T18:00:48.077Z,1526407248.077 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-05-15T18:00:48.078Z,1526407248.078 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-05-15T18:00:48.079Z,1526407248.079 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:48.172Z,1526407248.172 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:48.217Z,1526407248.217 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:48.265Z,1526407248.265 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:00:48.321Z,1526407248.321 [logger ThreadHandler](INFO): Thread cancelled.