2018-07-29T18:44:05.007Z,1532889845.007 [Supervisor](DEBUG): Initializing supervisor.
2018-07-29T18:44:05.010Z,1532889845.010 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-29T18:44:05.011Z,1532889845.011 [SyncHandler](INFO): Protected caller Thread ID is 6160
2018-07-29T18:44:05.011Z,1532889845.011 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-29T18:44:05.012Z,1532889845.012 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-29T18:44:05.012Z,1532889845.012 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6161
2018-07-29T18:44:05.016Z,1532889845.016 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-29T18:44:05.028Z,1532889845.028 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-29T18:44:05.029Z,1532889845.029 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-29T18:44:05.029Z,1532889845.029 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6162
2018-07-29T18:44:05.030Z,1532889845.030 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-29T18:44:05.031Z,1532889845.031 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-29T18:44:05.031Z,1532889845.031 [logger ThreadHandler](INFO): Protected caller Thread ID is 6163
2018-07-29T18:44:05.033Z,1532889845.033 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-29T18:44:05.034Z,1532889845.034 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-29T18:44:05.035Z,1532889845.035 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-29T18:44:05.288Z,1532889845.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-29T18:44:05.288Z,1532889845.288 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-29T18:44:05.431Z,1532889845.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-29T18:44:05.432Z,1532889845.432 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-29T18:44:05.778Z,1532889845.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-29T18:44:05.779Z,1532889845.779 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-29T18:44:05.917Z,1532889845.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-29T18:44:05.918Z,1532889845.918 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-29T18:44:05.998Z,1532889845.998 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-29T18:44:06.238Z,1532889846.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-29T18:44:06.238Z,1532889846.238 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-29T18:44:06.385Z,1532889846.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-29T18:44:06.386Z,1532889846.386 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-29T18:44:06.730Z,1532889846.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-29T18:44:06.731Z,1532889846.731 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-29T18:44:07.165Z,1532889847.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-29T18:44:07.165Z,1532889847.165 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-29T18:44:07.471Z,1532889847.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-29T18:44:07.472Z,1532889847.472 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-29T18:44:07.976Z,1532889847.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-29T18:44:07.977Z,1532889847.977 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-29T18:44:08.201Z,1532889848.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-29T18:44:08.201Z,1532889848.201 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-29T18:44:14.639Z,1532889854.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-29T18:44:14.640Z,1532889854.640 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-29T18:44:15.125Z,1532889855.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-29T18:44:15.126Z,1532889855.126 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-29T18:44:15.233Z,1532889855.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-29T18:44:15.235Z,1532889855.235 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-07-29T18:44:15.239Z,1532889855.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-07-29T18:44:15.470Z,1532889855.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-29T18:44:15.471Z,1532889855.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-07-29T18:44:15.569Z,1532889855.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-07-29T18:44:15.663Z,1532889855.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-07-29T18:44:15.766Z,1532889855.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-07-29T18:44:15.851Z,1532889855.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-07-29T18:44:15.983Z,1532889855.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-07-29T18:44:16.149Z,1532889856.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-07-29T18:44:16.259Z,1532889856.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-07-29T18:44:16.344Z,1532889856.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-07-29T18:44:16.427Z,1532889856.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-07-29T18:44:16.616Z,1532889856.616 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-07-29T18:44:16.617Z,1532889856.617 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-07-29T18:44:16.625Z,1532889856.625 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-29T18:44:16.778Z,1532889856.778 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-29T18:44:16.809Z,1532889856.809 [SBIT] Loaded
2018-07-29T18:44:16.809Z,1532889856.809 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-29T18:44:16.810Z,1532889856.810 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-29T18:44:16.838Z,1532889856.838 [IBIT] Loaded
2018-07-29T18:44:16.838Z,1532889856.838 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-29T18:44:16.841Z,1532889856.841 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-29T18:44:16.998Z,1532889856.998 [CBIT] Loaded
2018-07-29T18:44:16.998Z,1532889856.998 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-29T18:44:16.998Z,1532889856.998 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-29T18:44:16.999Z,1532889856.999 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-29T18:44:17.086Z,1532889857.086 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-29T18:44:17.186Z,1532889857.186 [VerticalControl] Loaded
2018-07-29T18:44:17.186Z,1532889857.186 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-29T18:44:17.187Z,1532889857.187 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-29T18:44:17.248Z,1532889857.248 [HorizontalControl] Loaded
2018-07-29T18:44:17.248Z,1532889857.248 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-29T18:44:17.249Z,1532889857.249 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-29T18:44:17.255Z,1532889857.255 [SpeedControl] Loaded
2018-07-29T18:44:17.255Z,1532889857.255 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-29T18:44:17.256Z,1532889857.256 [LoopControl](DEBUG): Construct LoopControl.
2018-07-29T18:44:17.256Z,1532889857.256 [LoopControl] Loaded
2018-07-29T18:44:17.257Z,1532889857.257 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-29T18:44:17.257Z,1532889857.257 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-29T18:44:17.258Z,1532889857.258 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-29T18:44:17.313Z,1532889857.313 [DepthRateCalculator] Loaded
2018-07-29T18:44:17.313Z,1532889857.313 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-29T18:44:17.318Z,1532889857.318 [PitchRateCalculator] Loaded
2018-07-29T18:44:17.319Z,1532889857.319 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-29T18:44:17.335Z,1532889857.335 [SpeedCalculator] Loaded
2018-07-29T18:44:17.335Z,1532889857.335 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-29T18:44:17.357Z,1532889857.357 [TempGradientCalculator] Loaded
2018-07-29T18:44:17.358Z,1532889857.358 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-29T18:44:17.373Z,1532889857.373 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-29T18:44:17.374Z,1532889857.374 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-29T18:44:17.379Z,1532889857.379 [YawRateCalculator] Loaded
2018-07-29T18:44:17.379Z,1532889857.379 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-29T18:44:17.408Z,1532889857.408 [ElevatorOffsetCalculator] Loaded
2018-07-29T18:44:17.409Z,1532889857.409 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-29T18:44:17.409Z,1532889857.409 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-29T18:44:17.410Z,1532889857.410 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-29T18:44:17.533Z,1532889857.533 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-29T18:44:17.533Z,1532889857.533 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-29T18:44:17.534Z,1532889857.534 [StratificationFrontDetector] Loaded
2018-07-29T18:44:17.534Z,1532889857.534 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-29T18:44:17.535Z,1532889857.535 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-29T18:44:17.535Z,1532889857.535 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-29T18:44:17.821Z,1532889857.821 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-29T18:44:17.821Z,1532889857.821 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-29T18:44:17.936Z,1532889857.936 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-07-29T18:44:17.937Z,1532889857.937 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-07-29T18:44:17.991Z,1532889857.991 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-29T18:44:17.992Z,1532889857.992 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-29T18:44:18.009Z,1532889858.009 [NavChart] Loaded
2018-07-29T18:44:18.009Z,1532889858.009 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-29T18:44:18.013Z,1532889858.013 [UniversalFixResidualReporter] Loaded
2018-07-29T18:44:18.013Z,1532889858.013 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-29T18:44:18.014Z,1532889858.014 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-29T18:44:18.014Z,1532889858.014 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-29T18:44:18.038Z,1532889858.038 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-29T18:44:18.038Z,1532889858.038 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-29T18:44:18.240Z,1532889858.240 [Aanderaa_O2] Loaded
2018-07-29T18:44:18.240Z,1532889858.240 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-07-29T18:44:18.291Z,1532889858.291 [WetLabsBB2FL] Loaded
2018-07-29T18:44:18.291Z,1532889858.291 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-29T18:44:18.294Z,1532889858.294 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406D34E0
2018-07-29T18:44:18.294Z,1532889858.294 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6243
2018-07-29T18:44:18.295Z,1532889858.295 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-29T18:44:18.295Z,1532889858.295 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-29T18:44:18.901Z,1532889858.901 [AHRS_M2] Loaded
2018-07-29T18:44:18.901Z,1532889858.901 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-07-29T18:44:18.989Z,1532889858.989 [DataOverHttps] Loaded
2018-07-29T18:44:18.990Z,1532889858.990 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-29T18:44:19.003Z,1532889859.003 [Depth_Keller] Loaded
2018-07-29T18:44:19.004Z,1532889859.004 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-29T18:44:19.008Z,1532889859.008 [DropWeight] Loaded
2018-07-29T18:44:19.009Z,1532889859.009 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-07-29T18:44:19.100Z,1532889859.100 [NAL9602] Loaded
2018-07-29T18:44:19.101Z,1532889859.101 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-29T18:44:19.106Z,1532889859.106 [Onboard] Loaded
2018-07-29T18:44:19.106Z,1532889859.106 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-29T18:44:19.113Z,1532889859.113 [Radio_Surface] Loaded
2018-07-29T18:44:19.113Z,1532889859.113 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-29T18:44:19.114Z,1532889859.114 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409294E0
2018-07-29T18:44:19.115Z,1532889859.115 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6244
2018-07-29T18:44:19.144Z,1532889859.144 [RDI_Pathfinder] Loaded
2018-07-29T18:44:19.144Z,1532889859.144 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-07-29T18:44:19.150Z,1532889859.150 [SCPI] Loaded
2018-07-29T18:44:19.151Z,1532889859.151 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-07-29T18:44:20.968Z,1532889860.968 [BPC1] Loaded
2018-07-29T18:44:20.968Z,1532889860.968 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-29T18:44:20.969Z,1532889860.969 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-29T18:44:20.969Z,1532889860.969 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-29T18:44:21.091Z,1532889861.091 [BuoyancyServo] Loaded
2018-07-29T18:44:21.091Z,1532889861.091 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-29T18:44:21.103Z,1532889861.103 [ElevatorServo] Loaded
2018-07-29T18:44:21.103Z,1532889861.103 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-29T18:44:21.114Z,1532889861.114 [MassServo] Loaded
2018-07-29T18:44:21.114Z,1532889861.114 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-29T18:44:21.126Z,1532889861.126 [RudderServo] Loaded
2018-07-29T18:44:21.126Z,1532889861.126 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-29T18:44:21.137Z,1532889861.137 [ThrusterServo] Loaded
2018-07-29T18:44:21.137Z,1532889861.137 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-29T18:44:21.137Z,1532889861.137 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-29T18:44:21.138Z,1532889861.138 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-29T18:44:24.173Z,1532889864.173 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-29T18:44:24.174Z,1532889864.174 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-29T18:44:24.213Z,1532889864.213 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-29T18:44:24.217Z,1532889864.217 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-29T18:44:24.218Z,1532889864.218 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-29T18:44:24.225Z,1532889864.225 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-29T18:44:24.226Z,1532889864.226 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D24E0
2018-07-29T18:44:24.226Z,1532889864.226 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6245
2018-07-29T18:44:24.231Z,1532889864.231 [Supervisor](INFO): Main Thread ID is 6159
2018-07-29T18:44:24.231Z,1532889864.231 [Supervisor](DEBUG): Running supervisor.
2018-07-29T18:44:24.232Z,1532889864.232 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6246
2018-07-29T18:44:24.234Z,1532889864.234 [controlThread ThreadHandler](INFO): Handler Thread ID is 6247
2018-07-29T18:44:24.235Z,1532889864.235 [controlThread](DEBUG): Initializing ControlThread
2018-07-29T18:44:24.235Z,1532889864.235 [SBIT](INFO): Initialize SBIT Component.
2018-07-29T18:44:24.236Z,1532889864.236 [SBIT](IMPORTANT): git: 2018-07-27-2-gcee2be5
2018-07-29T18:44:24.236Z,1532889864.236 [SBIT](INFO): git hash: cee2be563e54d2a232c086b65a88aa5c38dfc469
2018-07-29T18:44:24.237Z,1532889864.237 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-29T18:44:24.238Z,1532889864.238 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-29T18:44:24.239Z,1532889864.239 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2018-07-29T18:44:24.240Z,1532889864.240 [IBIT](INFO): Initialize IBIT Component.
2018-07-29T18:44:24.241Z,1532889864.241 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-29T18:44:24.241Z,1532889864.241 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-07-29T18:44:24.242Z,1532889864.242 [logger ThreadHandler](INFO): Handler Thread ID is 6248
2018-07-29T18:44:24.273Z,1532889864.273 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6249
2018-07-29T18:44:24.289Z,1532889864.289 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6250
2018-07-29T18:44:24.295Z,1532889864.295 [Radio_Surface](INFO): Powering up
2018-07-29T18:44:24.313Z,1532889864.313 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6251
2018-07-29T18:44:24.322Z,1532889864.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-29T18:44:24.322Z,1532889864.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-29T18:44:24.322Z,1532889864.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-29T18:44:24.322Z,1532889864.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-29T18:44:24.322Z,1532889864.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-29T18:44:24.323Z,1532889864.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-29T18:44:24.323Z,1532889864.323 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-29T18:44:24.323Z,1532889864.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-29T18:44:24.323Z,1532889864.323 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-29T18:44:24.323Z,1532889864.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-29T18:44:24.324Z,1532889864.324 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-29T18:44:24.324Z,1532889864.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-29T18:44:24.324Z,1532889864.324 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-29T18:44:24.324Z,1532889864.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-29T18:44:24.324Z,1532889864.324 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-29T18:44:24.324Z,1532889864.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-29T18:44:24.349Z,1532889864.349 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-29T18:44:24.351Z,1532889864.351 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-29T18:44:24.352Z,1532889864.352 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-29T18:44:24.352Z,1532889864.352 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-29T18:44:24.352Z,1532889864.352 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-29T18:44:24.353Z,1532889864.353 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-29T18:44:24.353Z,1532889864.353 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-29T18:44:24.353Z,1532889864.353 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-29T18:44:24.354Z,1532889864.354 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-29T18:44:24.355Z,1532889864.355 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-29T18:44:24.355Z,1532889864.355 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-29T18:44:24.356Z,1532889864.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-29T18:44:24.357Z,1532889864.357 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-29T18:44:24.357Z,1532889864.357 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-29T18:44:24.357Z,1532889864.357 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-29T18:44:24.358Z,1532889864.358 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-29T18:44:24.358Z,1532889864.358 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-29T18:44:24.358Z,1532889864.358 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-29T18:44:24.359Z,1532889864.359 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-29T18:44:24.366Z,1532889864.366 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-29T18:44:24.399Z,1532889864.399 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-29T18:44:24.420Z,1532889864.420 [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-07-29T18:44:24.441Z,1532889864.441 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-29T18:44:24.519Z,1532889864.519 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-29T18:44:24.520Z,1532889864.520 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-29T18:44:24.542Z,1532889864.542 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-29T18:44:24.575Z,1532889864.575 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-29T18:44:24.604Z,1532889864.604 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-29T18:44:24.626Z,1532889864.626 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-29T18:44:24.630Z,1532889864.630 [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 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-07-29T18:44:24.645Z,1532889864.645 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,Depth_Keller,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-07-29T18:44:24.697Z,1532889864.697 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-29T18:44:24.816Z,1532889864.816 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-07-29T18:44:24.837Z,1532889864.837 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-07-29T18:44:25.077Z,1532889865.077 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-07-29T18:44:25.181Z,1532889865.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:25.231Z,1532889865.231 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:25.355Z,1532889865.355 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-29T18:44:25.365Z,1532889865.365 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-29T18:44:25.379Z,1532889865.379 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-07-29T18:44:25.385Z,1532889865.385 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-07-29T18:44:25.399Z,1532889865.399 [MassServo](DEBUG): Initializing EZServoServo.
2018-07-29T18:44:25.402Z,1532889865.402 [MassServo](DEBUG): Initializing MassServo.
2018-07-29T18:44:25.424Z,1532889865.424 [RudderServo](DEBUG): Initializing EZServoServo.
2018-07-29T18:44:25.429Z,1532889865.429 [RudderServo](DEBUG): Initializing RudderServo.
2018-07-29T18:44:25.435Z,1532889865.435 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-29T18:44:25.441Z,1532889865.441 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-29T18:44:25.639Z,1532889865.639 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-07-29T18:44:25.639Z,1532889865.639 [DropWeight] Hardware Fault, FailCount= 1
2018-07-29T18:44:25.640Z,1532889865.640 [DropWeight](ERROR): Hardware Fault
2018-07-29T18:44:25.693Z,1532889865.693 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-07-29T18:44:25.697Z,1532889865.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:25.698Z,1532889865.698 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:25.716Z,1532889865.716 [CommandLine](FAULT): Scheduling is paused
2018-07-29T18:44:25.717Z,1532889865.717 [CBIT](INFO): Critical error at 20180729T184425
2018-07-29T18:44:25.717Z,1532889865.717 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-07-29T18:44:25.719Z,1532889865.719 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-07-29T18:44:25.720Z,1532889865.720 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-07-29T18:44:25.977Z,1532889865.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:25.978Z,1532889865.978 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:26.325Z,1532889866.325 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-07-29T18:44:26.325Z,1532889866.325 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-07-29T18:44:26.325Z,1532889866.325 [BuoyancyServo] Communications Fault, FailCount= 1
2018-07-29T18:44:26.325Z,1532889866.325 [BuoyancyServo](ERROR): Communications Fault
2018-07-29T18:44:26.535Z,1532889866.535 [CBIT](INFO): Critical error at 20180729T184425
2018-07-29T18:44:26.538Z,1532889866.538 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-29T18:44:26.597Z,1532889866.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:26.599Z,1532889866.599 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:26.611Z,1532889866.611 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-29T18:44:26.612Z,1532889866.612 [BuoyancyServo](INFO): Powering down
2018-07-29T18:44:26.977Z,1532889866.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:26.978Z,1532889866.978 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:27.385Z,1532889867.385 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:27.386Z,1532889867.386 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:27.865Z,1532889867.865 [Aanderaa_O2](INFO): Powering down
2018-07-29T18:44:27.989Z,1532889867.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:27.990Z,1532889867.990 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:36.673Z,1532889876.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:36.674Z,1532889876.674 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:36.801Z,1532889876.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:36.802Z,1532889876.802 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:36.926Z,1532889876.926 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-29T18:44:36.927Z,1532889876.927 [BuoyancyServo] No Fault, FailCount= 1
2018-07-29T18:44:37.201Z,1532889877.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:37.202Z,1532889877.202 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:37.208Z,1532889877.208 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-29T18:44:37.342Z,1532889877.342 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-29T18:44:37.630Z,1532889877.630 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004125
2018-07-29T18:44:37.670Z,1532889877.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:37.671Z,1532889877.671 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:38.139Z,1532889878.139 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:38.140Z,1532889878.140 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:38.517Z,1532889878.517 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-07-29T18:44:38.517Z,1532889878.517 [AHRS_M2] Communications Fault, FailCount= 1
2018-07-29T18:44:38.517Z,1532889878.517 [AHRS_M2](ERROR): Communications Fault
2018-07-29T18:44:38.552Z,1532889878.552 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-29T18:44:38.572Z,1532889878.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:38.581Z,1532889878.581 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:38.651Z,1532889878.651 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-07-29T18:44:38.886Z,1532889878.886 [AHRS_M2](INFO): Powering down
2018-07-29T18:44:39.010Z,1532889879.010 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:39.011Z,1532889879.011 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:39.314Z,1532889879.314 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:39.315Z,1532889879.315 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:39.767Z,1532889879.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:39.768Z,1532889879.768 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:40.114Z,1532889880.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:40.115Z,1532889880.115 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:40.542Z,1532889880.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:40.543Z,1532889880.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:40.918Z,1532889880.918 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:40.919Z,1532889880.919 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:41.314Z,1532889881.314 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:41.315Z,1532889881.315 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:41.742Z,1532889881.742 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:41.743Z,1532889881.743 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:42.118Z,1532889882.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:42.119Z,1532889882.119 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:42.542Z,1532889882.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:42.543Z,1532889882.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:42.610Z,1532889882.610 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-07-29T18:44:42.610Z,1532889882.610 [AHRS_M2] No Fault, FailCount= 1
2018-07-29T18:44:42.886Z,1532889882.886 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-29T18:44:42.939Z,1532889882.939 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:42.940Z,1532889882.940 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:43.315Z,1532889883.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:43.316Z,1532889883.316 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:43.734Z,1532889883.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:43.735Z,1532889883.735 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:44.114Z,1532889884.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:44.115Z,1532889884.115 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:44.534Z,1532889884.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:44.535Z,1532889884.535 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:44.914Z,1532889884.914 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:44.915Z,1532889884.915 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:45.314Z,1532889885.314 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:45.315Z,1532889885.315 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:45.747Z,1532889885.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:45.748Z,1532889885.748 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:46.158Z,1532889886.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:46.159Z,1532889886.159 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:46.578Z,1532889886.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:46.579Z,1532889886.579 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:46.970Z,1532889886.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:46.971Z,1532889886.971 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:47.342Z,1532889887.342 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:47.343Z,1532889887.343 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:47.841Z,1532889887.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:47.842Z,1532889887.842 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:48.138Z,1532889888.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:48.139Z,1532889888.139 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:48.557Z,1532889888.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:48.558Z,1532889888.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:48.942Z,1532889888.942 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:48.943Z,1532889888.943 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:49.314Z,1532889889.314 [NAL9602](INFO): Powering up NAL9602
2018-07-29T18:44:49.352Z,1532889889.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:49.357Z,1532889889.357 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:49.736Z,1532889889.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:49.745Z,1532889889.745 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:50.114Z,1532889890.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:50.115Z,1532889890.115 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:50.570Z,1532889890.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:44:50.571Z,1532889890.571 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-29T18:45:00.495Z,1532889900.495 [NAL9602](INFO): NAL9602 initialized
2018-07-29T18:45:03.808Z,1532889903.808 [SBIT](IMPORTANT): Beginning Startup BIT
2018-07-29T18:45:03.829Z,1532889903.829 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-29T18:45:14.819Z,1532889914.819 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010015
CHAN A1 (24V): -0.013385
CHAN A2 (12V): -0.007427
CHAN A3 (5V): -0.002290
CHAN B0 (3.3V): 0.000176
CHAN B1 (3.15aV): 0.000222
CHAN B2 (3.15bV): 0.000240
CHAN B3 (GND): 0.002154
OPEN: 0.004514
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-29T18:45:30.966Z,1532889930.966 [CommandLine](IMPORTANT): got command failComponent
2018-07-29T18:45:30.967Z,1532889930.967 [CommandLine](IMPORTANT): Failed components:
2018-07-29T18:45:30.968Z,1532889930.968 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-07-29T18:45:50.082Z,1532889950.082 [CommandLine](IMPORTANT): got command configSet AHRS_M2.verbosity 2.000000 count
2018-07-29T18:45:50.268Z,1532889950.268 [AHRS_M2](INFO): Mx: -59.826805 uT, My: -14.140627 uT, Mz: -18.980434 uT
2018-07-29T18:45:50.269Z,1532889950.269 [AHRS_M2](INFO): PITCH: -2.269371 deg, ROLL: -1.734036 deg, YAW: 316.232021 deg, TEMP: 27.989595 C.
2018-07-29T18:45:50.668Z,1532889950.668 [AHRS_M2](INFO): Mx: -59.776501 uT, My: -14.148763 uT, Mz: -18.997074 uT
2018-07-29T18:45:50.669Z,1532889950.669 [AHRS_M2](INFO): PITCH: -2.264686 deg, ROLL: -1.733775 deg, YAW: 316.240928 deg, TEMP: 27.998775 C.
2018-07-29T18:45:51.068Z,1532889951.068 [AHRS_M2](INFO): Mx: -59.802898 uT, My: -14.128438 uT, Mz: -18.997013 uT
2018-07-29T18:45:51.069Z,1532889951.069 [AHRS_M2](INFO): PITCH: -2.267560 deg, ROLL: -1.728971 deg, YAW: 316.246747 deg, TEMP: 27.983477 C.
2018-07-29T18:45:51.468Z,1532889951.468 [AHRS_M2](INFO): Mx: -59.789513 uT, My: -14.102971 uT, Mz: -18.976368 uT
2018-07-29T18:45:51.474Z,1532889951.474 [AHRS_M2](INFO): PITCH: -2.261299 deg, ROLL: -1.731540 deg, YAW: 316.256665 deg, TEMP: 28.004896 C.
2018-07-29T18:45:51.868Z,1532889951.868 [AHRS_M2](INFO): Mx: -59.787457 uT, My: -14.164950 uT, Mz: -18.984676 uT
2018-07-29T18:45:51.869Z,1532889951.869 [AHRS_M2](INFO): PITCH: -2.265715 deg, ROLL: -1.724350 deg, YAW: 316.263577 deg, TEMP: 28.011017 C.
2018-07-29T18:45:52.268Z,1532889952.268 [AHRS_M2](INFO): Mx: -59.818909 uT, My: -14.119814 uT, Mz: -18.980431 uT
2018-07-29T18:45:52.269Z,1532889952.269 [AHRS_M2](INFO): PITCH: -2.262070 deg, ROLL: -1.723200 deg, YAW: 316.272456 deg, TEMP: 27.995716 C.
2018-07-29T18:45:52.668Z,1532889952.668 [AHRS_M2](INFO): Mx: -59.783882 uT, My: -14.136121 uT, Mz: -18.964561 uT
2018-07-29T18:45:52.670Z,1532889952.670 [AHRS_M2](INFO): PITCH: -2.267770 deg, ROLL: -1.724947 deg, YAW: 316.257320 deg, TEMP: 28.004896 C.
2018-07-29T18:45:53.068Z,1532889953.068 [AHRS_M2](INFO): Mx: -59.812279 uT, My: -14.133060 uT, Mz: -19.001429 uT
2018-07-29T18:45:53.069Z,1532889953.069 [AHRS_M2](INFO): PITCH: -2.262846 deg, ROLL: -1.733934 deg, YAW: 316.243031 deg, TEMP: 27.971237 C.
2018-07-29T18:45:53.468Z,1532889953.468 [AHRS_M2](INFO): Mx: -59.795666 uT, My: -14.066175 uT, Mz: -18.988592 uT
2018-07-29T18:45:53.469Z,1532889953.469 [AHRS_M2](INFO): PITCH: -2.263860 deg, ROLL: -1.740325 deg, YAW: 316.231939 deg, TEMP: 27.974297 C.
2018-07-29T18:45:53.868Z,1532889953.868 [AHRS_M2](INFO): Mx: -59.806896 uT, My: -14.140727 uT, Mz: -18.973177 uT
2018-07-29T18:45:53.869Z,1532889953.869 [AHRS_M2](INFO): PITCH: -2.255724 deg, ROLL: -1.735024 deg, YAW: 316.259615 deg, TEMP: 27.986536 C.
2018-07-29T18:45:54.268Z,1532889954.268 [AHRS_M2](INFO): Mx: -59.807453 uT, My: -14.124519 uT, Mz: -18.984974 uT
2018-07-29T18:45:54.269Z,1532889954.269 [AHRS_M2](INFO): PITCH: -2.254848 deg, ROLL: -1.730578 deg, YAW: 316.272483 deg, TEMP: 27.995716 C.
2018-07-29T18:45:54.668Z,1532889954.668 [AHRS_M2](INFO): Mx: -59.777050 uT, My: -14.090723 uT, Mz: -18.984575 uT
2018-07-29T18:45:54.669Z,1532889954.669 [AHRS_M2](INFO): PITCH: -2.262107 deg, ROLL: -1.724799 deg, YAW: 316.270680 deg, TEMP: 27.998775 C.
2018-07-29T18:45:55.068Z,1532889955.068 [AHRS_M2](INFO): Mx: -59.783028 uT, My: -14.131546 uT, Mz: -18.988140 uT
2018-07-29T18:45:55.069Z,1532889955.069 [AHRS_M2](INFO): PITCH: -2.265230 deg, ROLL: -1.730891 deg, YAW: 316.251747 deg, TEMP: 27.992657 C.
2018-07-29T18:45:55.468Z,1532889955.468 [AHRS_M2](INFO): Mx: -59.781475 uT, My: -14.094806 uT, Mz: -19.004519 uT
2018-07-29T18:45:55.469Z,1532889955.469 [AHRS_M2](INFO): PITCH: -2.259882 deg, ROLL: -1.729108 deg, YAW: 316.266773 deg, TEMP: 27.992657 C.
2018-07-29T18:45:55.868Z,1532889955.868 [AHRS_M2](INFO): Mx: -59.802254 uT, My: -14.123892 uT, Mz: -19.020695 uT
2018-07-29T18:45:55.869Z,1532889955.869 [AHRS_M2](INFO): PITCH: -2.260281 deg, ROLL: -1.728261 deg, YAW: 316.265790 deg, TEMP: 27.998775 C.
2018-07-29T18:45:56.268Z,1532889956.268 [AHRS_M2](INFO): Mx: -59.781670 uT, My: -14.099287 uT, Mz: -18.988930 uT
2018-07-29T18:45:56.269Z,1532889956.269 [AHRS_M2](INFO): PITCH: -2.258046 deg, ROLL: -1.730611 deg, YAW: 316.267156 deg, TEMP: 27.992657 C.
2018-07-29T18:45:56.668Z,1532889956.668 [AHRS_M2](INFO): Mx: -59.785454 uT, My: -14.107389 uT, Mz: -18.992586 uT
2018-07-29T18:45:56.669Z,1532889956.669 [AHRS_M2](INFO): PITCH: -2.267791 deg, ROLL: -1.731726 deg, YAW: 316.247567 deg, TEMP: 27.980415 C.
2018-07-29T18:45:57.068Z,1532889957.068 [AHRS_M2](INFO): Mx: -59.818836 uT, My: -14.090757 uT, Mz: -19.000000 uT
2018-07-29T18:45:57.069Z,1532889957.069 [AHRS_M2](INFO): PITCH: -2.263886 deg, ROLL: -1.732644 deg, YAW: 316.250791 deg, TEMP: 27.974297 C.
2018-07-29T18:45:57.468Z,1532889957.468 [AHRS_M2](INFO): Mx: -59.775879 uT, My: -14.148351 uT, Mz: -19.008955 uT
2018-07-29T18:45:57.470Z,1532889957.470 [AHRS_M2](INFO): PITCH: -2.263643 deg, ROLL: -1.738742 deg, YAW: 316.238168 deg, TEMP: 27.983477 C.
2018-07-29T18:45:57.868Z,1532889957.868 [AHRS_M2](INFO): Mx: -59.813728 uT, My: -14.111511 uT, Mz: -19.011932 uT
2018-07-29T18:45:57.869Z,1532889957.869 [AHRS_M2](INFO): PITCH: -2.267251 deg, ROLL: -1.736454 deg, YAW: 316.238059 deg, TEMP: 27.989595 C.
2018-07-29T18:45:57.956Z,1532889957.956 [SBIT](IMPORTANT): SBIT PASSED
2018-07-29T18:45:57.997Z,1532889957.997 [CommandLine](IMPORTANT): got command configSet list
2018-07-29T18:45:57.998Z,1532889957.998 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-07-29T18:45:57.998Z,1532889957.998 [CommandLine](IMPORTANT): AHRS_M2.readAccelerations=0 bool;

2018-07-29T18:45:57.999Z,1532889957.999 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;

2018-07-29T18:45:57.999Z,1532889957.999 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-07-29T18:45:57.999Z,1532889957.999 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter;

2018-07-29T18:45:57.999Z,1532889957.999 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2018-07-29T18:45:58.268Z,1532889958.268 [AHRS_M2](INFO): Mx: -59.801506 uT, My: -14.094931 uT, Mz: -18.996147 uT
2018-07-29T18:45:58.269Z,1532889958.269 [AHRS_M2](INFO): PITCH: -2.265517 deg, ROLL: -1.728254 deg, YAW: 316.256692 deg, TEMP: 27.998775 C.
2018-07-29T18:45:58.342Z,1532889958.342 [MissionManager](IMPORTANT): Started mission Startup
2018-07-29T18:45:58.342Z,1532889958.342 [Startup] Running Loop=1
2018-07-29T18:45:58.342Z,1532889958.342 [Startup](DEBUG): Aggregate::initialize Startup
2018-07-29T18:45:58.342Z,1532889958.342 [Startup:A.GoToSurface] Running Loop=1
2018-07-29T18:45:58.342Z,1532889958.342 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-29T18:45:58.343Z,1532889958.343 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-29T18:45:58.344Z,1532889958.344 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-29T18:45:58.344Z,1532889958.344 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-29T18:45:58.344Z,1532889958.344 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-29T18:45:58.350Z,1532889958.350 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-29T18:45:58.367Z,1532889958.367 [Startup:StartupSatComms] Running Loop=1
2018-07-29T18:45:58.367Z,1532889958.367 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-07-29T18:45:58.367Z,1532889958.367 [Startup:StartupSatComms:A] Running Loop=1
2018-07-29T18:45:58.668Z,1532889958.668 [AHRS_M2](INFO): Mx: -59.818012 uT, My: -14.144925 uT, Mz: -19.004311 uT
2018-07-29T18:45:58.669Z,1532889958.669 [AHRS_M2](INFO): PITCH: -2.264557 deg, ROLL: -1.731914 deg, YAW: 316.253659 deg, TEMP: 27.986536 C.
2018-07-29T18:45:58.742Z,1532889958.742 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-07-29T18:45:59.068Z,1532889959.068 [AHRS_M2](INFO): Mx: -59.826191 uT, My: -14.140758 uT, Mz: -19.016212 uT
2018-07-29T18:45:59.069Z,1532889959.069 [AHRS_M2](INFO): PITCH: -2.259720 deg, ROLL: -1.731024 deg, YAW: 316.262129 deg, TEMP: 27.992657 C.
2018-07-29T18:45:59.468Z,1532889959.468 [AHRS_M2](INFO): Mx: -59.855354 uT, My: -14.065875 uT, Mz: -19.006466 uT
2018-07-29T18:45:59.469Z,1532889959.469 [AHRS_M2](INFO): PITCH: -2.258248 deg, ROLL: -1.737317 deg, YAW: 316.253905 deg, TEMP: 28.014076 C.
2018-07-29T18:45:59.868Z,1532889959.868 [AHRS_M2](INFO): Mx: -59.833916 uT, My: -14.140569 uT, Mz: -19.023798 uT
2018-07-29T18:45:59.869Z,1532889959.869 [AHRS_M2](INFO): PITCH: -2.259547 deg, ROLL: -1.733266 deg, YAW: 316.262402 deg, TEMP: 28.004896 C.
2018-07-29T18:46:00.268Z,1532889960.268 [AHRS_M2](INFO): Mx: -59.837597 uT, My: -14.132276 uT, Mz: -19.023521 uT
2018-07-29T18:46:00.269Z,1532889960.269 [AHRS_M2](INFO): PITCH: -2.256003 deg, ROLL: -1.733454 deg, YAW: 316.269587 deg, TEMP: 27.998775 C.
2018-07-29T18:46:00.668Z,1532889960.668 [AHRS_M2](INFO): Mx: -59.805134 uT, My: -14.161321 uT, Mz: -19.004528 uT
2018-07-29T18:46:00.669Z,1532889960.669 [AHRS_M2](INFO): PITCH: -2.255235 deg, ROLL: -1.737039 deg, YAW: 316.265271 deg, TEMP: 27.983477 C.
2018-07-29T18:46:01.068Z,1532889961.068 [AHRS_M2](INFO): Mx: -59.814201 uT, My: -14.119929 uT, Mz: -19.016376 uT
2018-07-29T18:46:01.069Z,1532889961.069 [AHRS_M2](INFO): PITCH: -2.257116 deg, ROLL: -1.736187 deg, YAW: 316.262784 deg, TEMP: 27.998775 C.
2018-07-29T18:46:01.468Z,1532889961.468 [AHRS_M2](INFO): Mx: -59.767223 uT, My: -14.156647 uT, Mz: -19.001026 uT
2018-07-29T18:46:01.469Z,1532889961.469 [AHRS_M2](INFO): PITCH: -2.256486 deg, ROLL: -1.737000 deg, YAW: 316.260653 deg, TEMP: 27.992657 C.
2018-07-29T18:46:01.868Z,1532889961.868 [AHRS_M2](INFO): Mx: -59.760254 uT, My: -14.148931 uT, Mz: -19.013451 uT
2018-07-29T18:46:01.869Z,1532889961.869 [AHRS_M2](INFO): PITCH: -2.260891 deg, ROLL: -1.737436 deg, YAW: 316.249534 deg, TEMP: 27.977356 C.
2018-07-29T18:46:02.268Z,1532889962.268 [AHRS_M2](INFO): Mx: -59.826714 uT, My: -14.149155 uT, Mz: -19.004156 uT
2018-07-29T18:46:02.269Z,1532889962.269 [AHRS_M2](INFO): PITCH: -2.256724 deg, ROLL: -1.736270 deg, YAW: 316.261418 deg, TEMP: 27.965117 C.
2018-07-29T18:46:02.668Z,1532889962.668 [AHRS_M2](INFO): Mx: -59.840015 uT, My: -14.149280 uT, Mz: -19.016308 uT
2018-07-29T18:46:02.669Z,1532889962.669 [AHRS_M2](INFO): PITCH: -2.258976 deg, ROLL: -1.740321 deg, YAW: 316.252102 deg, TEMP: 27.971237 C.
2018-07-29T18:46:03.068Z,1532889963.068 [AHRS_M2](INFO): Mx: -59.796497 uT, My: -14.157312 uT, Mz: -18.992708 uT
2018-07-29T18:46:03.073Z,1532889963.073 [AHRS_M2](INFO): PITCH: -2.256502 deg, ROLL: -1.743508 deg, YAW: 316.252703 deg, TEMP: 27.995716 C.
2018-07-29T18:46:03.468Z,1532889963.468 [AHRS_M2](INFO): Mx: -59.785347 uT, My: -14.136671 uT, Mz: -19.036430 uT
2018-07-29T18:46:03.469Z,1532889963.469 [AHRS_M2](INFO): PITCH: -2.259509 deg, ROLL: -1.742717 deg, YAW: 316.251774 deg, TEMP: 27.986536 C.
2018-07-29T18:46:03.868Z,1532889963.868 [AHRS_M2](INFO): Mx: -59.780445 uT, My: -14.136238 uT, Mz: -19.000727 uT
2018-07-29T18:46:03.869Z,1532889963.869 [AHRS_M2](INFO): PITCH: -2.263800 deg, ROLL: -1.737695 deg, YAW: 316.252648 deg, TEMP: 27.989595 C.
2018-07-29T18:46:04.268Z,1532889964.268 [AHRS_M2](INFO): Mx: -59.808983 uT, My: -14.157061 uT, Mz: -19.008425 uT
2018-07-29T18:46:04.269Z,1532889964.269 [AHRS_M2](INFO): PITCH: -2.253173 deg, ROLL: -1.737572 deg, YAW: 316.273194 deg, TEMP: 27.998775 C.
2018-07-29T18:46:04.668Z,1532889964.668 [AHRS_M2](INFO): Mx: -59.804260 uT, My: -14.148549 uT, Mz: -18.980099 uT
2018-07-29T18:46:04.669Z,1532889964.669 [AHRS_M2](INFO): PITCH: -2.255555 deg, ROLL: -1.735813 deg, YAW: 316.269232 deg, TEMP: 27.995716 C.
2018-07-29T18:46:05.068Z,1532889965.068 [AHRS_M2](INFO): Mx: -59.805164 uT, My: -14.144597 uT, Mz: -18.968620 uT
2018-07-29T18:46:05.069Z,1532889965.069 [AHRS_M2](INFO): PITCH: -2.252963 deg, ROLL: -1.738917 deg, YAW: 316.266309 deg, TEMP: 27.992657 C.
2018-07-29T18:46:05.468Z,1532889965.468 [AHRS_M2](INFO): Mx: -59.815788 uT, My: -14.111979 uT, Mz: -18.992737 uT
2018-07-29T18:46:05.469Z,1532889965.469 [AHRS_M2](INFO): PITCH: -2.254724 deg, ROLL: -1.741204 deg, YAW: 316.256528 deg, TEMP: 27.980415 C.
2018-07-29T18:46:05.868Z,1532889965.868 [AHRS_M2](INFO): Mx: -59.781975 uT, My: -14.103287 uT, Mz: -19.008808 uT
2018-07-29T18:46:05.869Z,1532889965.869 [AHRS_M2](INFO): PITCH: -2.254462 deg, ROLL: -1.739085 deg, YAW: 316.261746 deg, TEMP: 27.986536 C.
2018-07-29T18:46:06.268Z,1532889966.268 [AHRS_M2](INFO): Mx: -59.810394 uT, My: -14.132220 uT, Mz: -18.968588 uT
2018-07-29T18:46:06.269Z,1532889966.269 [AHRS_M2](INFO): PITCH: -2.258637 deg, ROLL: -1.736677 deg, YAW: 316.257785 deg, TEMP: 27.980415 C.
2018-07-29T18:46:06.668Z,1532889966.668 [AHRS_M2](INFO): Mx: -59.818481 uT, My: -14.144724 uT, Mz: -18.976561 uT
2018-07-29T18:46:06.669Z,1532889966.669 [AHRS_M2](INFO): PITCH: -2.253634 deg, ROLL: -1.736663 deg, YAW: 316.268030 deg, TEMP: 28.007956 C.
2018-07-29T18:46:07.068Z,1532889967.068 [AHRS_M2](INFO): Mx: -59.836090 uT, My: -14.112009 uT, Mz: -18.992168 uT
2018-07-29T18:46:07.069Z,1532889967.069 [AHRS_M2](INFO): PITCH: -2.253101 deg, ROLL: -1.734471 deg, YAW: 316.270298 deg, TEMP: 27.986536 C.
2018-07-29T18:46:07.468Z,1532889967.468 [AHRS_M2](INFO): Mx: -59.793022 uT, My: -14.107466 uT, Mz: -18.980171 uT
2018-07-29T18:46:07.469Z,1532889967.469 [AHRS_M2](INFO): PITCH: -2.257842 deg, ROLL: -1.735487 deg, YAW: 316.260462 deg, TEMP: 27.998775 C.
2018-07-29T18:46:07.868Z,1532889967.868 [AHRS_M2](INFO): Mx: -59.796040 uT, My: -14.119164 uT, Mz: -19.011801 uT
2018-07-29T18:46:07.869Z,1532889967.869 [AHRS_M2](INFO): PITCH: -2.255325 deg, ROLL: -1.731875 deg, YAW: 316.272647 deg, TEMP: 28.014076 C.
2018-07-29T18:46:08.268Z,1532889968.268 [AHRS_M2](INFO): Mx: -59.815132 uT, My: -14.099101 uT, Mz: -19.008144 uT
2018-07-29T18:46:08.269Z,1532889968.269 [AHRS_M2](INFO): PITCH: -2.253827 deg, ROLL: -1.735676 deg, YAW: 316.263604 deg, TEMP: 27.986536 C.
2018-07-29T18:46:08.668Z,1532889968.668 [AHRS_M2](INFO): Mx: -59.776344 uT, My: -14.140210 uT, Mz: -18.993076 uT
2018-07-29T18:46:08.669Z,1532889968.669 [AHRS_M2](INFO): PITCH: -2.255139 deg, ROLL: -1.737541 deg, YAW: 316.264287 deg, TEMP: 27.995716 C.
2018-07-29T18:46:09.068Z,1532889969.068 [AHRS_M2](INFO): Mx: -59.845161 uT, My: -14.124697 uT, Mz: -18.956804 uT
2018-07-29T18:46:09.069Z,1532889969.069 [AHRS_M2](INFO): PITCH: -2.254871 deg, ROLL: -1.742737 deg, YAW: 316.253113 deg, TEMP: 28.004896 C.
2018-07-29T18:46:09.468Z,1532889969.468 [AHRS_M2](INFO): Mx: -59.780651 uT, My: -14.106997 uT, Mz: -18.996239 uT
2018-07-29T18:46:09.470Z,1532889969.470 [AHRS_M2](INFO): PITCH: -2.257833 deg, ROLL: -1.738497 deg, YAW: 316.253441 deg, TEMP: 27.986536 C.
2018-07-29T18:46:09.868Z,1532889969.868 [AHRS_M2](INFO): Mx: -59.813911 uT, My: -14.132396 uT, Mz: -19.000191 uT
2018-07-29T18:46:09.869Z,1532889969.869 [AHRS_M2](INFO): PITCH: -2.265861 deg, ROLL: -1.737025 deg, YAW: 316.240245 deg, TEMP: 27.992657 C.
2018-07-29T18:46:10.268Z,1532889970.268 [AHRS_M2](INFO): Mx: -59.807728 uT, My: -14.135847 uT, Mz: -19.043736 uT
2018-07-29T18:46:10.269Z,1532889970.269 [AHRS_M2](INFO): PITCH: -2.263461 deg, ROLL: -1.739271 deg, YAW: 316.236693 deg, TEMP: 28.001837 C.
2018-07-29T18:46:10.668Z,1532889970.668 [AHRS_M2](INFO): Mx: -59.789894 uT, My: -14.086752 uT, Mz: -19.004377 uT
2018-07-29T18:46:10.669Z,1532889970.669 [AHRS_M2](INFO): PITCH: -2.256086 deg, ROLL: -1.739284 deg, YAW: 316.249807 deg, TEMP: 28.001837 C.
2018-07-29T18:46:11.068Z,1532889971.068 [AHRS_M2](INFO): Mx: -59.790596 uT, My: -14.082777 uT, Mz: -18.996510 uT
2018-07-29T18:46:11.069Z,1532889971.069 [AHRS_M2](INFO): PITCH: -2.255314 deg, ROLL: -1.734894 deg, YAW: 316.263194 deg, TEMP: 28.004896 C.
2018-07-29T18:46:11.468Z,1532889971.468 [AHRS_M2](INFO): Mx: -59.787224 uT, My: -14.082937 uT, Mz: -18.988955 uT
2018-07-29T18:46:11.469Z,1532889971.469 [AHRS_M2](INFO): PITCH: -2.257275 deg, ROLL: -1.737042 deg, YAW: 316.258140 deg, TEMP: 27.989595 C.
2018-07-29T18:46:11.868Z,1532889971.868 [AHRS_M2](INFO): Mx: -59.818634 uT, My: -14.099382 uT, Mz: -18.995905 uT
2018-07-29T18:46:11.869Z,1532889971.869 [AHRS_M2](INFO): PITCH: -2.255948 deg, ROLL: -1.738155 deg, YAW: 316.257894 deg, TEMP: 27.995716 C.
2018-07-29T18:46:12.268Z,1532889972.268 [AHRS_M2](INFO): Mx: -59.785774 uT, My: -14.119787 uT, Mz: -19.016811 uT
2018-07-29T18:46:12.269Z,1532889972.269 [AHRS_M2](INFO): PITCH: -2.252733 deg, ROLL: -1.741292 deg, YAW: 316.259943 deg, TEMP: 27.971237 C.
2018-07-29T18:46:12.668Z,1532889972.668 [AHRS_M2](INFO): Mx: -59.779694 uT, My: -14.106952 uT, Mz: -19.000341 uT
2018-07-29T18:46:12.669Z,1532889972.669 [AHRS_M2](INFO): PITCH: -2.253200 deg, ROLL: -1.738893 deg, YAW: 316.265462 deg, TEMP: 27.986536 C.
2018-07-29T18:46:13.068Z,1532889973.068 [AHRS_M2](INFO): Mx: -59.809441 uT, My: -14.119864 uT, Mz: -19.008060 uT
2018-07-29T18:46:13.069Z,1532889973.069 [AHRS_M2](INFO): PITCH: -2.256562 deg, ROLL: -1.732669 deg, YAW: 316.264861 deg, TEMP: 27.980415 C.
2018-07-29T18:46:13.471Z,1532889973.471 [AHRS_M2](INFO): Mx: -59.803577 uT, My: -14.135935 uT, Mz: -19.023539 uT
2018-07-29T18:46:13.472Z,1532889973.472 [AHRS_M2](INFO): PITCH: -2.257705 deg, ROLL: -1.735355 deg, YAW: 316.260981 deg, TEMP: 27.995716 C.
2018-07-29T18:46:13.868Z,1532889973.868 [AHRS_M2](INFO): Mx: -59.793560 uT, My: -14.140508 uT, Mz: -19.020899 uT
2018-07-29T18:46:13.873Z,1532889973.873 [AHRS_M2](INFO): PITCH: -2.255170 deg, ROLL: -1.732322 deg, YAW: 316.269478 deg, TEMP: 27.992657 C.
2018-07-29T18:46:14.268Z,1532889974.268 [AHRS_M2](INFO): Mx: -59.816307 uT, My: -14.127729 uT, Mz: -18.991640 uT
2018-07-29T18:46:14.269Z,1532889974.269 [AHRS_M2](INFO): PITCH: -2.252682 deg, ROLL: -1.735102 deg, YAW: 316.268057 deg, TEMP: 27.989595 C.
2018-07-29T18:46:14.668Z,1532889974.668 [AHRS_M2](INFO): Mx: -59.855328 uT, My: -14.120127 uT, Mz: -18.991522 uT
2018-07-29T18:46:14.669Z,1532889974.669 [AHRS_M2](INFO): PITCH: -2.258956 deg, ROLL: -1.740296 deg, YAW: 316.251228 deg, TEMP: 27.998775 C.
2018-07-29T18:46:15.068Z,1532889975.068 [AHRS_M2](INFO): Mx: -59.816948 uT, My: -14.123838 uT, Mz: -19.019588 uT
2018-07-29T18:46:15.069Z,1532889975.069 [AHRS_M2](INFO): PITCH: -2.261201 deg, ROLL: -1.738379 deg, YAW: 316.247375 deg, TEMP: 27.998775 C.
2018-07-29T18:46:15.470Z,1532889975.470 [AHRS_M2](INFO): Mx: -59.823933 uT, My: -14.128842 uT, Mz: -18.988564 uT
2018-07-29T18:46:15.471Z,1532889975.471 [AHRS_M2](INFO): PITCH: -2.252207 deg, ROLL: -1.736487 deg, YAW: 316.267101 deg, TEMP: 27.989595 C.
2018-07-29T18:46:15.868Z,1532889975.868 [AHRS_M2](INFO): Mx: -59.804119 uT, My: -14.152677 uT, Mz: -19.020174 uT
2018-07-29T18:46:15.869Z,1532889975.869 [AHRS_M2](INFO): PITCH: -2.260312 deg, ROLL: -1.736663 deg, YAW: 316.251118 deg, TEMP: 27.992657 C.
2018-07-29T18:46:16.268Z,1532889976.268 [AHRS_M2](INFO): Mx: -59.814007 uT, My: -14.124147 uT, Mz: -18.976393 uT
2018-07-29T18:46:16.270Z,1532889976.270 [AHRS_M2](INFO): PITCH: -2.264887 deg, ROLL: -1.732704 deg, YAW: 316.248414 deg, TEMP: 28.001837 C.
2018-07-29T18:46:16.622Z,1532889976.622 [CommandLine](IMPORTANT): got command configSet AHRS_M2.verbosity 0.000000 count
2018-07-29T18:46:58.481Z,1532890018.481 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-29T18:45:58.4Z
2018-07-29T18:46:58.481Z,1532890018.481 [Startup:StartupSatComms:A] Stopped
2018-07-29T18:46:58.482Z,1532890018.482 [Startup:StartupSatComms:B] Running Loop=1
2018-07-29T18:46:58.854Z,1532890018.854 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-29T18:47:03.730Z,1532890023.730 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180729T180016/Courier0007.lzma
2018-07-29T18:47:04.519Z,1532890024.519 [DataOverHttps](INFO): Moved sent file to Logs/20180729T180016/Courier0007.lzma.bak
2018-07-29T18:47:04.519Z,1532890024.519 [DataOverHttps](INFO): SBD MOMSN=8384022
2018-07-29T18:47:10.696Z,1532890030.696 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-29T18:47:14.336Z,1532890034.336 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180729T181032/Courier0000.lzma
2018-07-29T18:47:15.115Z,1532890035.115 [DataOverHttps](INFO): Moved sent file to Logs/20180729T181032/Courier0000.lzma.bak
2018-07-29T18:47:15.115Z,1532890035.115 [DataOverHttps](INFO): SBD MOMSN=8384024
2018-07-29T18:47:25.012Z,1532890045.012 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-29T18:47:25.012Z,1532890045.012 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-07-29T18:47:25.012Z,1532890045.012 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-29T18:47:25.014Z,1532890045.014 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-29T18:47:25.014Z,1532890045.014 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-07-29T18:47:25.014Z,1532890045.014 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-29T18:47:25.027Z,1532890045.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-29T18:47:25.028Z,1532890045.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-29T18:47:25.100Z,1532890045.100 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180729T181249/Courier0000.lzma
2018-07-29T18:47:25.183Z,1532890045.183 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-29T18:47:25.183Z,1532890045.183 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-07-29T18:47:25.184Z,1532890045.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-29T18:47:25.184Z,1532890045.184 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-07-29T18:47:25.540Z,1532890045.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-29T18:47:25.557Z,1532890045.557 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-29T18:47:25.557Z,1532890045.557 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-29T18:47:25.558Z,1532890045.558 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-29T18:47:25.559Z,1532890045.559 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-29T18:47:25.560Z,1532890045.560 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-29T18:47:25.887Z,1532890045.887 [DataOverHttps](INFO): Moved sent file to Logs/20180729T181249/Courier0000.lzma.bak
2018-07-29T18:47:25.887Z,1532890045.887 [DataOverHttps](INFO): SBD MOMSN=8384029
2018-07-29T18:47:25.978Z,1532890045.978 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.0 s old, using for 20.0 s.
2018-07-29T18:47:26.337Z,1532890046.337 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.4 s old, using for 20.0 s.
2018-07-29T18:47:26.745Z,1532890046.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.8 s old, using for 20.0 s.
2018-07-29T18:47:29.999Z,1532890049.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.0 s old, using for 20.0 s.
2018-07-29T18:47:30.127Z,1532890050.127 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2018-07-29T18:47:30.599Z,1532890050.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-29T18:47:30.903Z,1532890050.903 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2018-07-29T18:47:31.315Z,1532890051.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2018-07-29T18:47:31.752Z,1532890051.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-29T18:47:35.020Z,1532890055.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-29T18:47:35.094Z,1532890055.094 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180729T184404/Courier0000.lzma
2018-07-29T18:47:35.168Z,1532890055.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2018-07-29T18:47:35.521Z,1532890055.521 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2018-07-29T18:47:35.888Z,1532890055.888 [DataOverHttps](INFO): Moved sent file to Logs/20180729T184404/Courier0000.lzma.bak
2018-07-29T18:47:35.888Z,1532890055.888 [DataOverHttps](INFO): SBD MOMSN=8384034
2018-07-29T18:47:36.012Z,1532890056.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2018-07-29T18:47:36.364Z,1532890056.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2018-07-29T18:47:36.735Z,1532890056.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2018-07-29T18:47:39.990Z,1532890059.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2018-07-29T18:47:40.106Z,1532890060.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.1 s old, using for 20.0 s.
2018-07-29T18:47:40.512Z,1532890060.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2018-07-29T18:47:40.907Z,1532890060.907 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2018-07-29T18:47:41.306Z,1532890061.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s.
2018-07-29T18:47:41.708Z,1532890061.708 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2018-07-29T18:47:42.085Z,1532890062.085 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2018-07-29T18:47:42.503Z,1532890062.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2018-07-29T18:47:45.823Z,1532890065.823 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20180729T180016/Express0008.lzma
2018-07-29T18:47:46.127Z,1532890066.127 [CommandLine](IMPORTANT): got command configSet AHRS_M2.readAccelerations 1.000000 bool persist
2018-07-29T18:47:46.127Z,1532890066.127 [CommandLine](IMPORTANT): configSet AHRS_M2.readAccelerations requires a restart to take effect.
2018-07-29T18:47:46.603Z,1532890066.603 [DataOverHttps](INFO): Moved sent file to Logs/20180729T180016/Express0008.lzma.bak
2018-07-29T18:47:46.603Z,1532890066.603 [DataOverHttps](INFO): SBD MOMSN=8384039
2018-07-29T18:47:56.526Z,1532890076.526 [DataOverHttps](INFO): Sending 877 bytes from file Logs/20180729T181032/Express0001.lzma
2018-07-29T18:47:57.299Z,1532890077.299 [DataOverHttps](INFO): Moved sent file to Logs/20180729T181032/Express0001.lzma.bak
2018-07-29T18:47:57.299Z,1532890077.299 [DataOverHttps](INFO): SBD MOMSN=8384046
2018-07-29T18:47:58.343Z,1532890078.343 [CommandLine](IMPORTANT): got command restart application
2018-07-29T18:47:59.379Z,1532890079.379 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-29T18:47:59.379Z,1532890079.379 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.553Z,1532890079.553 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-07-29T18:47:59.553Z,1532890079.553 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.554Z,1532890079.554 [CommandLine](INFO): Join timeout helper Thread ID is 6275
2018-07-29T18:47:59.571Z,1532890079.571 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-07-29T18:47:59.571Z,1532890079.571 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.571Z,1532890079.571 [NavChartDb](INFO): Join timeout helper Thread ID is 6276
2018-07-29T18:47:59.657Z,1532890079.657 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-29T18:47:59.657Z,1532890079.657 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.661Z,1532890079.661 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-07-29T18:47:59.661Z,1532890079.661 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.661Z,1532890079.661 [Radio_Surface](INFO): Join timeout helper Thread ID is 6277
2018-07-29T18:47:59.713Z,1532890079.713 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-29T18:47:59.713Z,1532890079.713 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.725Z,1532890079.725 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-07-29T18:47:59.725Z,1532890079.725 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.725Z,1532890079.725 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6278
2018-07-29T18:47:59.825Z,1532890079.825 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-29T18:47:59.825Z,1532890079.825 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.837Z,1532890079.837 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-07-29T18:47:59.837Z,1532890079.837 [logger ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.837Z,1532890079.837 [logger](INFO): Join timeout helper Thread ID is 6279
2018-07-29T18:47:59.865Z,1532890079.865 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-29T18:47:59.865Z,1532890079.865 [logger ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.870Z,1532890079.870 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-07-29T18:47:59.870Z,1532890079.870 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.870Z,1532890079.870 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-07-29T18:47:59.870Z,1532890079.870 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:47:59.871Z,1532890079.871 [controlThread](INFO): Join timeout helper Thread ID is 6280
2018-07-29T18:48:01.310Z,1532890081.310 [Startup:StartupSatComms:B](INFO): Timed out from 2018-07-29T18:46:58.5Z
2018-07-29T18:48:01.310Z,1532890081.310 [Startup:StartupSatComms:B] Stopped
2018-07-29T18:48:01.311Z,1532890081.311 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-07-29T18:48:01.311Z,1532890081.311 [Startup:StartupSatComms] Stopped
2018-07-29T18:48:01.311Z,1532890081.311 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-07-29T18:48:01.312Z,1532890081.312 [Startup](INFO): Completed Startup
2018-07-29T18:48:01.312Z,1532890081.312 [MissionManager](INFO): Startup is completed.
2018-07-29T18:48:01.312Z,1532890081.312 [MissionManager](INFO): Uninitializing Mission Startup
2018-07-29T18:48:01.312Z,1532890081.312 [Startup] Stopped
2018-07-29T18:48:01.312Z,1532890081.312 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-07-29T18:48:01.312Z,1532890081.312 [Startup:A.GoToSurface] Stopped
2018-07-29T18:48:01.313Z,1532890081.313 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-29T18:48:01.326Z,1532890081.326 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-29T18:48:01.326Z,1532890081.326 [controlThread](DEBUG): Uninitializing ControlThread
2018-07-29T18:48:01.327Z,1532890081.327 [Aanderaa_O2](INFO): Powering down
2018-07-29T18:48:01.328Z,1532890081.328 [AHRS_M2](INFO): Powering down
2018-07-29T18:48:01.404Z,1532890081.404 [NAL9602](INFO): Powering down
2018-07-29T18:48:01.477Z,1532890081.477 [RDI_Pathfinder](INFO): Powering down
2018-07-29T18:48:01.479Z,1532890081.479 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-29T18:48:01.480Z,1532890081.480 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-07-29T18:48:01.481Z,1532890081.481 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-07-29T18:48:01.482Z,1532890081.482 [MissionManager](INFO): Uninitializing Mission Default
2018-07-29T18:48:01.485Z,1532890081.485 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-07-29T18:48:01.485Z,1532890081.485 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-07-29T18:48:01.485Z,1532890081.485 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-07-29T18:48:01.486Z,1532890081.486 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-07-29T18:48:01.486Z,1532890081.486 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-29T18:48:01.486Z,1532890081.486 [BuoyancyServo](INFO): Powering down
2018-07-29T18:48:01.501Z,1532890081.501 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-07-29T18:48:01.501Z,1532890081.501 [ElevatorServo](INFO): Powering down
2018-07-29T18:48:01.502Z,1532890081.502 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-07-29T18:48:01.502Z,1532890081.502 [MassServo](INFO): Powering down
2018-07-29T18:48:01.503Z,1532890081.503 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-07-29T18:48:01.503Z,1532890081.503 [RudderServo](INFO): Powering down
2018-07-29T18:48:01.504Z,1532890081.504 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-07-29T18:48:01.504Z,1532890081.504 [ThrusterServo](INFO): Powering down
2018-07-29T18:48:01.505Z,1532890081.505 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-07-29T18:48:01.505Z,1532890081.505 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-07-29T18:48:01.506Z,1532890081.506 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-07-29T18:48:01.507Z,1532890081.507 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:48:01.565Z,1532890081.565 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:48:01.626Z,1532890081.626 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:48:01.669Z,1532890081.669 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-29T18:48:01.727Z,1532890081.727 [logger ThreadHandler](INFO): Thread cancelled.