2018-10-30T22:20:06.761Z,1540938006.761 [Supervisor](DEBUG): Initializing supervisor.
2018-10-30T22:20:06.771Z,1540938006.771 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-30T22:20:06.771Z,1540938006.771 [SyncHandler](INFO): Protected caller Thread ID is 6880
2018-10-30T22:20:06.772Z,1540938006.772 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-30T22:20:06.773Z,1540938006.773 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-30T22:20:06.773Z,1540938006.773 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6881
2018-10-30T22:20:06.777Z,1540938006.777 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-30T22:20:06.796Z,1540938006.796 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-30T22:20:06.797Z,1540938006.797 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-30T22:20:06.798Z,1540938006.798 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6882
2018-10-30T22:20:06.799Z,1540938006.799 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-30T22:20:06.799Z,1540938006.799 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-30T22:20:06.800Z,1540938006.800 [logger ThreadHandler](INFO): Protected caller Thread ID is 6883
2018-10-30T22:20:06.802Z,1540938006.802 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-30T22:20:06.802Z,1540938006.802 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-30T22:20:06.804Z,1540938006.804 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-30T22:20:07.330Z,1540938007.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-30T22:20:07.331Z,1540938007.331 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-30T22:20:07.797Z,1540938007.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-30T22:20:07.798Z,1540938007.798 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-30T22:20:07.000Z,1540938008.000 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-30T22:20:08.001Z,1540938008.001 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-30T22:20:08.151Z,1540938008.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-30T22:20:08.151Z,1540938008.151 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-30T22:20:08.612Z,1540938008.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-30T22:20:08.613Z,1540938008.613 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-30T22:20:08.711Z,1540938008.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-30T22:20:08.712Z,1540938008.712 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-30T22:20:08.857Z,1540938008.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-30T22:20:08.858Z,1540938008.858 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-30T22:20:08.961Z,1540938008.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-30T22:20:08.962Z,1540938008.962 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-30T22:20:09.068Z,1540938009.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-30T22:20:09.069Z,1540938009.069 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-30T22:20:09.428Z,1540938009.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-30T22:20:09.429Z,1540938009.429 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-30T22:20:09.642Z,1540938009.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-30T22:20:09.642Z,1540938009.642 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-30T22:20:09.863Z,1540938009.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-30T22:20:09.864Z,1540938009.864 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-30T22:20:09.946Z,1540938009.946 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-30T22:20:10.317Z,1540938010.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-30T22:20:10.318Z,1540938010.318 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-30T22:20:10.649Z,1540938010.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-30T22:20:10.651Z,1540938010.651 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-10-30T22:20:10.654Z,1540938010.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-10-30T22:20:10.740Z,1540938010.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-10-30T22:20:10.898Z,1540938010.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-10-30T22:20:10.999Z,1540938010.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-10-30T22:20:11.181Z,1540938011.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-10-30T22:20:11.265Z,1540938011.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-10-30T22:20:11.498Z,1540938011.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-30T22:20:11.499Z,1540938011.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-10-30T22:20:11.658Z,1540938011.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-10-30T22:20:12.266Z,1540938012.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-10-30T22:20:12.443Z,1540938012.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-10-30T22:20:12.550Z,1540938012.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-10-30T22:20:12.666Z,1540938012.666 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2018-10-30T22:20:12.666Z,1540938012.666 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-30T22:20:12.675Z,1540938012.675 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-30T22:20:12.735Z,1540938012.735 [DepthRateCalculator] Loaded
2018-10-30T22:20:12.735Z,1540938012.735 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-30T22:20:12.741Z,1540938012.741 [PitchRateCalculator] Loaded
2018-10-30T22:20:12.742Z,1540938012.742 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-30T22:20:12.758Z,1540938012.758 [SpeedCalculator] Loaded
2018-10-30T22:20:12.758Z,1540938012.758 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-30T22:20:12.780Z,1540938012.780 [TempGradientCalculator] Loaded
2018-10-30T22:20:12.780Z,1540938012.780 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-30T22:20:12.786Z,1540938012.786 [YawRateCalculator] Loaded
2018-10-30T22:20:12.787Z,1540938012.787 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-30T22:20:12.828Z,1540938012.828 [ElevatorOffsetCalculator] Loaded
2018-10-30T22:20:12.829Z,1540938012.829 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-30T22:20:12.829Z,1540938012.829 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-30T22:20:12.830Z,1540938012.830 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-30T22:20:13.129Z,1540938013.129 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-30T22:20:13.130Z,1540938013.130 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-30T22:20:13.518Z,1540938013.518 [DataOverHttps] Loaded
2018-10-30T22:20:13.519Z,1540938013.519 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-30T22:20:13.544Z,1540938013.544 [Depth_Keller] Loaded
2018-10-30T22:20:13.545Z,1540938013.545 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-30T22:20:13.550Z,1540938013.550 [DropWeight] Loaded
2018-10-30T22:20:13.550Z,1540938013.550 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-30T22:20:13.653Z,1540938013.653 [NAL9602] Loaded
2018-10-30T22:20:13.653Z,1540938013.653 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-30T22:20:13.670Z,1540938013.670 [Onboard] Loaded
2018-10-30T22:20:13.670Z,1540938013.670 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-30T22:20:13.681Z,1540938013.681 [Radio_Surface] Loaded
2018-10-30T22:20:13.681Z,1540938013.681 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-30T22:20:13.682Z,1540938013.682 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-10-30T22:20:13.683Z,1540938013.683 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6962
2018-10-30T22:20:13.689Z,1540938013.689 [SCPI] Loaded
2018-10-30T22:20:13.690Z,1540938013.690 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-10-30T22:20:15.535Z,1540938015.535 [BPC1] Loaded
2018-10-30T22:20:15.536Z,1540938015.536 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-10-30T22:20:15.536Z,1540938015.536 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-30T22:20:15.537Z,1540938015.537 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-30T22:20:15.644Z,1540938015.644 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-30T22:20:15.644Z,1540938015.644 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-30T22:20:15.761Z,1540938015.761 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-10-30T22:20:15.761Z,1540938015.761 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-10-30T22:20:15.817Z,1540938015.817 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-30T22:20:15.818Z,1540938015.818 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-30T22:20:15.835Z,1540938015.835 [NavChart] Loaded
2018-10-30T22:20:15.835Z,1540938015.835 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-30T22:20:15.839Z,1540938015.839 [UniversalFixResidualReporter] Loaded
2018-10-30T22:20:15.839Z,1540938015.839 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-30T22:20:15.840Z,1540938015.840 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-30T22:20:15.840Z,1540938015.840 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-30T22:20:16.125Z,1540938016.125 [CTD_NeilBrown] Loaded
2018-10-30T22:20:16.125Z,1540938016.125 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-10-30T22:20:16.126Z,1540938016.126 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-10-30T22:20:16.127Z,1540938016.127 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6963
2018-10-30T22:20:16.179Z,1540938016.179 [WetLabsBB2FL] Loaded
2018-10-30T22:20:16.179Z,1540938016.179 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-30T22:20:16.180Z,1540938016.180 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-10-30T22:20:16.180Z,1540938016.180 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6964
2018-10-30T22:20:16.181Z,1540938016.181 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-30T22:20:16.182Z,1540938016.182 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-30T22:20:16.273Z,1540938016.273 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-30T22:20:16.376Z,1540938016.376 [VerticalControl] Loaded
2018-10-30T22:20:16.377Z,1540938016.377 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-30T22:20:16.378Z,1540938016.378 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-30T22:20:16.450Z,1540938016.450 [HorizontalControl] Loaded
2018-10-30T22:20:16.450Z,1540938016.450 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-30T22:20:16.451Z,1540938016.451 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-30T22:20:16.456Z,1540938016.456 [SpeedControl] Loaded
2018-10-30T22:20:16.456Z,1540938016.456 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-30T22:20:16.457Z,1540938016.457 [LoopControl](DEBUG): Construct LoopControl.
2018-10-30T22:20:16.458Z,1540938016.458 [LoopControl] Loaded
2018-10-30T22:20:16.458Z,1540938016.458 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-30T22:20:16.458Z,1540938016.458 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-30T22:20:16.459Z,1540938016.459 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-30T22:20:16.483Z,1540938016.483 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-30T22:20:16.484Z,1540938016.484 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-30T22:20:16.892Z,1540938016.892 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-30T22:20:16.904Z,1540938016.904 [SBIT] Loaded
2018-10-30T22:20:16.904Z,1540938016.904 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-30T22:20:16.905Z,1540938016.905 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-30T22:20:16.916Z,1540938016.916 [IBIT] Loaded
2018-10-30T22:20:16.917Z,1540938016.917 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-30T22:20:16.920Z,1540938016.920 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-30T22:20:17.121Z,1540938017.121 [CBIT] Loaded
2018-10-30T22:20:17.121Z,1540938017.121 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-30T22:20:17.122Z,1540938017.122 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-30T22:20:17.122Z,1540938017.122 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-30T22:20:17.360Z,1540938017.360 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-30T22:20:17.360Z,1540938017.360 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-30T22:20:17.399Z,1540938017.399 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-30T22:20:17.399Z,1540938017.399 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-30T22:20:17.522Z,1540938017.522 [BuoyancyServo] Loaded
2018-10-30T22:20:17.522Z,1540938017.522 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-30T22:20:17.534Z,1540938017.534 [ElevatorServo] Loaded
2018-10-30T22:20:17.534Z,1540938017.534 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-30T22:20:17.545Z,1540938017.545 [MassServo] Loaded
2018-10-30T22:20:17.546Z,1540938017.546 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-30T22:20:17.557Z,1540938017.557 [RudderServo] Loaded
2018-10-30T22:20:17.557Z,1540938017.557 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-30T22:20:17.569Z,1540938017.569 [ThrusterServo] Loaded
2018-10-30T22:20:17.569Z,1540938017.569 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-30T22:20:17.569Z,1540938017.569 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-30T22:20:17.573Z,1540938017.573 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-30T22:20:17.574Z,1540938017.574 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-30T22:20:17.581Z,1540938017.581 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-30T22:20:17.582Z,1540938017.582 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2018-10-30T22:20:17.583Z,1540938017.583 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6965
2018-10-30T22:20:17.588Z,1540938017.588 [Supervisor](INFO): Main Thread ID is 5713
2018-10-30T22:20:17.588Z,1540938017.588 [Supervisor](DEBUG): Running supervisor.
2018-10-30T22:20:17.589Z,1540938017.589 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6966
2018-10-30T22:20:17.591Z,1540938017.591 [controlThread ThreadHandler](INFO): Handler Thread ID is 6967
2018-10-30T22:20:17.592Z,1540938017.592 [controlThread](DEBUG): Initializing ControlThread
2018-10-30T22:20:17.592Z,1540938017.592 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-30T22:20:17.593Z,1540938017.593 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-30T22:20:17.593Z,1540938017.593 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-30T22:20:17.593Z,1540938017.593 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-30T22:20:17.594Z,1540938017.594 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-30T22:20:17.594Z,1540938017.594 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-30T22:20:17.600Z,1540938017.600 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-30T22:20:17.601Z,1540938017.601 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:20:17.601Z,1540938017.601 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:20:17.602Z,1540938017.602 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-30T22:20:17.602Z,1540938017.602 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:20:17.602Z,1540938017.602 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:20:17.603Z,1540938017.603 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-30T22:20:17.603Z,1540938017.603 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-30T22:20:17.603Z,1540938017.603 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-30T22:20:17.605Z,1540938017.605 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-30T22:20:17.606Z,1540938017.606 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-30T22:20:17.606Z,1540938017.606 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-30T22:20:17.607Z,1540938017.607 [SBIT](INFO): Initialize SBIT Component.
2018-10-30T22:20:17.607Z,1540938017.607 [SBIT](IMPORTANT): git: 2018-10-09-45-g2ba51c3
2018-10-30T22:20:17.607Z,1540938017.607 [SBIT](INFO): git hash: 2ba51c3684b0a49cdfc0413537bdbfe1abb16683
2018-10-30T22:20:17.608Z,1540938017.608 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-30T22:20:17.608Z,1540938017.608 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-30T22:20:17.609Z,1540938017.609 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-10-30T22:20:17.610Z,1540938017.610 [IBIT](INFO): Initialize IBIT Component.
2018-10-30T22:20:17.610Z,1540938017.610 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-30T22:20:17.611Z,1540938017.611 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-30T22:20:17.612Z,1540938017.612 [logger ThreadHandler](INFO): Handler Thread ID is 6968
2018-10-30T22:20:17.629Z,1540938017.629 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6969
2018-10-30T22:20:17.635Z,1540938017.635 [Radio_Surface](INFO): Powering up
2018-10-30T22:20:17.645Z,1540938017.645 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6970
2018-10-30T22:20:17.653Z,1540938017.653 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6971
2018-10-30T22:20:17.654Z,1540938017.654 [WetLabsBB2FL](INFO): Powering down
2018-10-30T22:20:17.689Z,1540938017.689 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6972
2018-10-30T22:20:17.697Z,1540938017.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-30T22:20:17.697Z,1540938017.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-30T22:20:17.697Z,1540938017.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-30T22:20:17.697Z,1540938017.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-30T22:20:17.697Z,1540938017.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-30T22:20:17.698Z,1540938017.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-30T22:20:17.698Z,1540938017.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-30T22:20:17.698Z,1540938017.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-30T22:20:17.698Z,1540938017.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-30T22:20:17.698Z,1540938017.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-30T22:20:17.699Z,1540938017.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-30T22:20:17.699Z,1540938017.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-30T22:20:17.699Z,1540938017.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-30T22:20:17.699Z,1540938017.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-30T22:20:17.699Z,1540938017.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-30T22:20:17.699Z,1540938017.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-30T22:20:17.719Z,1540938017.719 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-30T22:20:17.747Z,1540938017.747 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-30T22:20:17.766Z,1540938017.766 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-10-30T22:20:17.767Z,1540938017.767 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-30T22:20:17.860Z,1540938017.860 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-30T22:20:17.877Z,1540938017.877 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-30T22:20:17.879Z,1540938017.879 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-30T22:20:17.902Z,1540938017.902 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-30T22:20:17.921Z,1540938017.921 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-30T22:20:17.927Z,1540938017.927 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-30T22:20:17.951Z,1540938017.951 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-10-30T22:20:17.956Z,1540938017.956 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-30T22:20:17.986Z,1540938017.986 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-30T22:20:18.019Z,1540938018.019 [Depth_Keller](ERROR): Pressure reading out of range: 1801.317627 decibar
2018-10-30T22:20:18.081Z,1540938018.081 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-30T22:20:18.081Z,1540938018.081 [Onboard](FAULT): Can't read temperature LSB.
2018-10-30T22:20:18.082Z,1540938018.082 [Onboard] Hardware Fault, FailCount= 1
2018-10-30T22:20:18.082Z,1540938018.082 [Onboard](ERROR): Hardware Fault
2018-10-30T22:20:18.084Z,1540938018.084 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-30T22:20:18.084Z,1540938018.084 [Onboard](FAULT): Can't read temperature MSB.
2018-10-30T22:20:18.085Z,1540938018.085 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-30T22:20:18.085Z,1540938018.085 [Onboard](FAULT): Can't read pressure LSB.
2018-10-30T22:20:18.095Z,1540938018.095 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-30T22:20:18.095Z,1540938018.095 [Onboard](FAULT): Can't read pressure MSB.
2018-10-30T22:20:18.147Z,1540938018.147 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-30T22:20:18.381Z,1540938018.381 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-30T22:20:18.389Z,1540938018.389 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-30T22:20:18.411Z,1540938018.411 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-30T22:20:18.417Z,1540938018.417 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-30T22:20:18.431Z,1540938018.431 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-30T22:20:18.437Z,1540938018.437 [MassServo](DEBUG): Initializing MassServo.
2018-10-30T22:20:18.445Z,1540938018.445 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-30T22:20:18.453Z,1540938018.453 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-30T22:20:18.459Z,1540938018.459 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-30T22:20:18.466Z,1540938018.466 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-30T22:20:18.501Z,1540938018.501 [CBIT](ERROR): Hardware Fault in component: Onboard
2018-10-30T22:20:18.501Z,1540938018.501 [CBIT](CRITICAL): Hardware Fault in component: Onboard
2018-10-30T22:20:18.599Z,1540938018.599 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-10-30T22:20:18.599Z,1540938018.599 [DropWeight] Hardware Fault, FailCount= 1
2018-10-30T22:20:18.599Z,1540938018.599 [DropWeight](ERROR): Hardware Fault
2018-10-30T22:20:18.609Z,1540938018.609 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-30T22:20:18.646Z,1540938018.646 [CommandLine](FAULT): Scheduling is paused
2018-10-30T22:20:18.646Z,1540938018.646 [CBIT](INFO): Critical error at 20181030T222018
2018-10-30T22:20:18.646Z,1540938018.646 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-30T22:20:18.649Z,1540938018.649 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-10-30T22:20:18.649Z,1540938018.649 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-10-30T22:20:19.281Z,1540938019.281 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-30T22:20:19.281Z,1540938019.281 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-30T22:20:19.281Z,1540938019.281 [BuoyancyServo] Communications Fault, FailCount= 1
2018-10-30T22:20:19.281Z,1540938019.281 [BuoyancyServo](ERROR): Communications Fault
2018-10-30T22:20:19.491Z,1540938019.491 [CBIT](INFO): Critical error at 20181030T222018
2018-10-30T22:20:19.494Z,1540938019.494 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-30T22:20:19.550Z,1540938019.550 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-30T22:20:19.551Z,1540938019.551 [BuoyancyServo](INFO): Powering down
2018-10-30T22:20:22.037Z,1540938022.037 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-30T22:20:22.037Z,1540938022.037 [BuoyancyServo] No Fault, FailCount= 1
2018-10-30T22:20:22.318Z,1540938022.318 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-30T22:20:22.438Z,1540938022.438 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-30T22:20:23.290Z,1540938023.290 [Radio_Surface](INFO): Powering down
2018-10-30T22:20:27.985Z,1540938027.985 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2139208%. Temp:4817016 C. ABORTING MISSION
2018-10-30T22:20:28.382Z,1540938028.382 [CBIT](INFO): Critical error at 20181030T222027
2018-10-30T22:20:43.966Z,1540938043.966 [NAL9602](INFO): Powering up NAL9602
2018-10-30T22:20:46.405Z,1540938046.405 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-30T22:20:46.410Z,1540938046.410 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-30T22:20:54.854Z,1540938054.854 [NAL9602](INFO): NAL9602 initialized
2018-10-30T22:20:57.350Z,1540938057.350 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008377
CHAN A1 (24V): 0.001756
CHAN A2 (12V): -0.005208
CHAN A3 (5V): -0.002836
CHAN B0 (3.3V): -0.001334
CHAN B1 (3.15aV): -0.001752
CHAN B2 (3.15bV): -0.001526
CHAN B3 (GND): -0.000239
OPEN: -0.001281
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-30T22:21:27.210Z,1540938087.210 [CommandLine](IMPORTANT): got command get depth
2018-10-30T22:21:27.211Z,1540938087.211 [CommandLine](IMPORTANT): depth 0.608347 m
2018-10-30T22:21:32.149Z,1540938092.149 [CommandLine](IMPORTANT): got command get depth
2018-10-30T22:21:32.150Z,1540938092.150 [CommandLine](IMPORTANT): depth 0.597135 m
2018-10-30T22:21:34.299Z,1540938094.299 [CommandLine](IMPORTANT): got command get depth
2018-10-30T22:21:34.299Z,1540938094.299 [CommandLine](IMPORTANT): depth 0.594549 m
2018-10-30T22:21:40.335Z,1540938100.335 [SBIT](IMPORTANT): SBIT PASSED
2018-10-30T22:21:40.401Z,1540938100.401 [CommandLine](IMPORTANT): got command configSet list
2018-10-30T22:21:40.401Z,1540938100.401 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-30T22:21:40.406Z,1540938100.406 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-10-30T22:21:40.407Z,1540938100.407 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-10-30T22:21:40.407Z,1540938100.407 [CommandLine](IMPORTANT): Depth_Keller.offset=-20.6875 decibar;

2018-10-30T22:21:40.407Z,1540938100.407 [CommandLine](IMPORTANT): Depth_Keller.scale=43.44 micropascal;

2018-10-30T22:21:40.407Z,1540938100.407 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=0 bool;

2018-10-30T22:21:40.707Z,1540938100.707 [MissionManager](IMPORTANT): Started mission Startup
2018-10-30T22:21:40.707Z,1540938100.707 [Startup] Running Loop=1
2018-10-30T22:21:40.707Z,1540938100.707 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-30T22:21:40.707Z,1540938100.707 [Startup:A.GoToSurface] Running Loop=1
2018-10-30T22:21:40.708Z,1540938100.708 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-30T22:21:40.708Z,1540938100.708 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-30T22:21:40.709Z,1540938100.709 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-30T22:21:40.709Z,1540938100.709 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-30T22:21:40.710Z,1540938100.710 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-30T22:21:40.710Z,1540938100.710 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-30T22:21:40.716Z,1540938100.716 [Startup:StartupSatComms] Running Loop=1
2018-10-30T22:21:40.716Z,1540938100.716 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-30T22:21:40.716Z,1540938100.716 [Startup:StartupSatComms:A] Running Loop=1
2018-10-30T22:21:41.014Z,1540938101.014 [Radio_Surface](INFO): Powering up
2018-10-30T22:21:41.108Z,1540938101.108 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-30T22:21:55.438Z,1540938115.438 [CommandLine](IMPORTANT): got command get sea_water_pressure
2018-10-30T22:21:55.439Z,1540938115.439 [CommandLine](IMPORTANT): sea_water_pressure 0.604181 db
2018-10-30T22:22:17.970Z,1540938137.970 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:22:17.970Z,1540938137.970 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-10-30T22:22:17.971Z,1540938137.971 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-30T22:22:17.972Z,1540938137.972 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:22:17.972Z,1540938137.972 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-30T22:22:17.972Z,1540938137.972 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-30T22:22:17.985Z,1540938137.985 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-30T22:22:17.985Z,1540938137.985 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-30T22:22:18.349Z,1540938138.349 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-30T22:22:18.349Z,1540938138.349 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-10-30T22:22:18.350Z,1540938138.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-30T22:22:18.350Z,1540938138.350 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-30T22:22:18.784Z,1540938138.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-30T22:22:18.785Z,1540938138.785 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:22:18.785Z,1540938138.785 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:22:18.786Z,1540938138.786 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-30T22:22:18.786Z,1540938138.786 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:22:18.786Z,1540938138.786 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:22:24.345Z,1540938144.345 [CommandLine](IMPORTANT): got command failComponent
2018-10-30T22:22:24.345Z,1540938144.345 [CommandLine](IMPORTANT): Failed components:
2018-10-30T22:22:24.346Z,1540938144.346 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-10-30T22:22:24.346Z,1540938144.346 [CommandLine](IMPORTANT): Onboard: Hardware Fault
2018-10-30T22:22:42.795Z,1540938162.795 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-30T22:21:40.7Z
2018-10-30T22:22:42.795Z,1540938162.795 [Startup:StartupSatComms:A] Stopped
2018-10-30T22:22:42.796Z,1540938162.796 [Startup:StartupSatComms:B] Running Loop=1
2018-10-30T22:22:42.904Z,1540938162.904 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-30T22:22:43.651Z,1540938163.651 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004634
2018-10-30T22:22:47.689Z,1540938167.689 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181030T170553/Courier0073.lzma
2018-10-30T22:22:48.467Z,1540938168.467 [DataOverHttps](INFO): Moved sent file to Logs/20181030T170553/Courier0073.lzma.bak
2018-10-30T22:22:48.467Z,1540938168.467 [DataOverHttps](INFO): SBD MOMSN=8755283
2018-10-30T22:22:57.143Z,1540938177.143 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20181030T222006/Courier0000.lzma
2018-10-30T22:22:57.943Z,1540938177.943 [DataOverHttps](INFO): Moved sent file to Logs/20181030T222006/Courier0000.lzma.bak
2018-10-30T22:22:57.943Z,1540938177.943 [DataOverHttps](INFO): SBD MOMSN=8755285
2018-10-30T22:23:06.757Z,1540938186.757 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20181030T170553/Express0074.lzma
2018-10-30T22:23:07.529Z,1540938187.529 [DataOverHttps](INFO): Moved sent file to Logs/20181030T170553/Express0074.lzma.bak
2018-10-30T22:23:07.529Z,1540938187.529 [DataOverHttps](INFO): SBD MOMSN=8755292
2018-10-30T22:23:16.396Z,1540938196.396 [DataOverHttps](INFO): Sending 1102 bytes from file Logs/20181030T222006/Express0001.lzma
2018-10-30T22:23:17.167Z,1540938197.167 [DataOverHttps](INFO): Moved sent file to Logs/20181030T222006/Express0001.lzma.bak
2018-10-30T22:23:17.167Z,1540938197.167 [DataOverHttps](INFO): SBD MOMSN=8755299
2018-10-30T22:23:17.705Z,1540938197.705 [Startup:StartupSatComms:B] Stopped
2018-10-30T22:23:17.706Z,1540938197.706 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-30T22:23:17.706Z,1540938197.706 [Startup:StartupSatComms] Stopped
2018-10-30T22:23:17.706Z,1540938197.706 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-30T22:23:17.707Z,1540938197.707 [Startup](INFO): Completed Startup
2018-10-30T22:23:17.707Z,1540938197.707 [MissionManager](INFO): Startup is completed.
2018-10-30T22:23:17.707Z,1540938197.707 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-30T22:23:17.707Z,1540938197.707 [Startup] Stopped
2018-10-30T22:23:17.707Z,1540938197.707 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-30T22:23:17.707Z,1540938197.707 [Startup:A.GoToSurface] Stopped
2018-10-30T22:23:17.707Z,1540938197.707 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-30T22:23:17.991Z,1540938197.991 [MissionManager](IMPORTANT): Started mission Default
2018-10-30T22:23:17.991Z,1540938197.991 [Default] Running Loop=1
2018-10-30T22:23:17.991Z,1540938197.991 [Default](DEBUG): Aggregate::initialize Default
2018-10-30T22:23:17.991Z,1540938197.991 [Default:B.GoToSurface] Running Loop=1
2018-10-30T22:23:17.991Z,1540938197.991 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-30T22:23:17.992Z,1540938197.992 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-30T22:23:17.992Z,1540938197.992 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-30T22:23:17.992Z,1540938197.992 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-30T22:23:17.993Z,1540938197.993 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-30T22:23:17.993Z,1540938197.993 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-30T22:23:17.993Z,1540938197.993 [Default:A.Wait] Running Loop=1
2018-10-30T22:23:17.993Z,1540938197.993 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-30T22:23:31.160Z,1540938211.160 [Default:A.Wait](INFO): Done Waiting.
2018-10-30T22:23:31.160Z,1540938211.160 [Default:A.Wait] Stopped
2018-10-30T22:23:31.160Z,1540938211.160 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-10-30T22:23:31.560Z,1540938211.560 [Default:CheckIn] Running Loop=1
2018-10-30T22:23:31.560Z,1540938211.560 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-30T22:23:31.560Z,1540938211.560 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-30T22:23:31.960Z,1540938211.960 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-10-30T22:24:18.816Z,1540938258.816 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:24:18.816Z,1540938258.816 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-10-30T22:24:18.816Z,1540938258.816 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-30T22:24:18.818Z,1540938258.818 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:24:18.818Z,1540938258.818 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-30T22:24:18.818Z,1540938258.818 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-30T22:24:18.832Z,1540938258.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-30T22:24:18.832Z,1540938258.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-30T22:24:19.232Z,1540938259.232 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-30T22:24:19.232Z,1540938259.232 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-10-30T22:24:19.233Z,1540938259.233 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-30T22:24:19.233Z,1540938259.233 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-30T22:24:19.596Z,1540938259.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-30T22:24:19.597Z,1540938259.597 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:24:19.597Z,1540938259.597 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:24:19.598Z,1540938259.598 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-30T22:24:19.598Z,1540938259.598 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:24:19.598Z,1540938259.598 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:24:42.646Z,1540938282.646 [CommandLine](IMPORTANT): got command get Onboard.Temperature
2018-10-30T22:24:42.647Z,1540938282.647 [CommandLine](IMPORTANT): Onboard.Temperature nan degC
2018-10-30T22:24:51.815Z,1540938291.815 [CommandLine](IMPORTANT): got command failComponent none Onboard
2018-10-30T22:24:51.815Z,1540938291.815 [Onboard] No Fault, FailCount= 1
2018-10-30T22:24:51.815Z,1540938291.815 [CommandLine](IMPORTANT): Onboard failureMode is No Fault
2018-10-30T22:24:52.368Z,1540938292.368 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-30T22:24:52.368Z,1540938292.368 [Onboard](FAULT): Can't read temperature LSB.
2018-10-30T22:24:52.368Z,1540938292.368 [Onboard] Hardware Fault, FailCount= 2
2018-10-30T22:24:52.368Z,1540938292.368 [Onboard](ERROR): Hardware Fault
2018-10-30T22:24:52.377Z,1540938292.377 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-30T22:24:52.377Z,1540938292.377 [Onboard](FAULT): Can't read temperature MSB.
2018-10-30T22:24:52.382Z,1540938292.382 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-30T22:24:52.382Z,1540938292.382 [Onboard](FAULT): Can't read pressure LSB.
2018-10-30T22:24:52.389Z,1540938292.389 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-30T22:24:52.390Z,1540938292.390 [Onboard](FAULT): Can't read pressure MSB.
2018-10-30T22:24:52.428Z,1540938292.428 [CBIT](ERROR): Hardware Fault in component: Onboard
2018-10-30T22:24:52.428Z,1540938292.428 [CBIT](CRITICAL): Hardware Fault in component: Onboard
2018-10-30T22:24:52.808Z,1540938292.808 [CBIT](INFO): Critical error at 20181030T222452
2018-10-30T22:25:58.250Z,1540938358.250 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-10-30T22:26:19.801Z,1540938379.801 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:26:19.801Z,1540938379.801 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-10-30T22:26:19.801Z,1540938379.801 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-30T22:26:19.802Z,1540938379.802 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:26:19.803Z,1540938379.803 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-10-30T22:26:19.803Z,1540938379.803 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-30T22:26:19.815Z,1540938379.815 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-30T22:26:19.816Z,1540938379.816 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-30T22:26:20.208Z,1540938380.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-30T22:26:20.208Z,1540938380.208 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-10-30T22:26:20.210Z,1540938380.210 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-30T22:26:20.210Z,1540938380.210 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-10-30T22:26:20.591Z,1540938380.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-30T22:26:20.591Z,1540938380.591 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:26:20.591Z,1540938380.591 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:26:20.592Z,1540938380.592 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-30T22:26:20.592Z,1540938380.592 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:26:20.593Z,1540938380.593 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:27:45.742Z,1540938465.742 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-10-30T22:28:20.877Z,1540938500.877 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:28:20.877Z,1540938500.877 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-10-30T22:28:20.877Z,1540938500.877 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-30T22:28:20.893Z,1540938500.893 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:28:20.893Z,1540938500.893 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-10-30T22:28:20.893Z,1540938500.893 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-30T22:28:20.919Z,1540938500.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-30T22:28:20.919Z,1540938500.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-30T22:28:21.309Z,1540938501.309 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-30T22:28:21.309Z,1540938501.309 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-10-30T22:28:21.310Z,1540938501.310 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-30T22:28:21.310Z,1540938501.310 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-10-30T22:28:21.695Z,1540938501.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-30T22:28:21.696Z,1540938501.696 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:28:21.696Z,1540938501.696 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:28:21.696Z,1540938501.696 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-30T22:28:21.697Z,1540938501.697 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:28:21.697Z,1540938501.697 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:28:31.703Z,1540938511.703 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-30T22:23:31.6Z
2018-10-30T22:28:31.703Z,1540938511.703 [Default:CheckIn:Read_GPS] Stopped
2018-10-30T22:28:31.703Z,1540938511.703 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-30T22:28:32.099Z,1540938512.099 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-30T22:28:35.988Z,1540938515.988 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20181030T222006/Courier0004.lzma
2018-10-30T22:28:36.783Z,1540938516.783 [DataOverHttps](INFO): Moved sent file to Logs/20181030T222006/Courier0004.lzma.bak
2018-10-30T22:28:36.783Z,1540938516.783 [DataOverHttps](INFO): SBD MOMSN=8755337
2018-10-30T22:28:45.414Z,1540938525.414 [DataOverHttps](INFO): Sending 416 bytes from file Logs/20181030T222006/Express0005.lzma
2018-10-30T22:28:46.195Z,1540938526.195 [DataOverHttps](INFO): Moved sent file to Logs/20181030T222006/Express0005.lzma.bak
2018-10-30T22:28:46.195Z,1540938526.195 [DataOverHttps](INFO): SBD MOMSN=8755340
2018-10-30T22:28:46.913Z,1540938526.913 [Default:CheckIn:Read_Iridium] Stopped
2018-10-30T22:28:46.913Z,1540938526.913 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-30T22:28:46.913Z,1540938526.913 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-30T22:30:21.899Z,1540938621.899 [CommandLine](IMPORTANT): got command restart application
2018-10-30T22:30:22.026Z,1540938622.026 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:30:22.026Z,1540938622.026 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2018-10-30T22:30:22.026Z,1540938622.026 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-30T22:30:22.028Z,1540938622.028 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-30T22:30:22.028Z,1540938622.028 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-10-30T22:30:22.028Z,1540938622.028 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-30T22:30:22.057Z,1540938622.057 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-30T22:30:22.058Z,1540938622.058 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-30T22:30:22.463Z,1540938622.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-30T22:30:22.463Z,1540938622.463 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2018-10-30T22:30:22.464Z,1540938622.464 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-30T22:30:22.464Z,1540938622.464 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-10-30T22:30:22.812Z,1540938622.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-30T22:30:22.813Z,1540938622.813 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:30:22.813Z,1540938622.813 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:30:22.814Z,1540938622.814 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-30T22:30:22.814Z,1540938622.814 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-30T22:30:22.814Z,1540938622.814 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-30T22:30:22.908Z,1540938622.908 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-30T22:30:22.908Z,1540938622.908 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:22.917Z,1540938622.917 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-30T22:30:22.917Z,1540938622.917 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:22.918Z,1540938622.918 [CommandLine](INFO): Join timeout helper Thread ID is 6997
2018-10-30T22:30:22.918Z,1540938622.918 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-30T22:30:22.918Z,1540938622.918 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:22.919Z,1540938622.919 [NavChartDb](INFO): Join timeout helper Thread ID is 6998
2018-10-30T22:30:23.249Z,1540938623.249 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-30T22:30:23.249Z,1540938623.249 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:23.265Z,1540938623.265 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-30T22:30:23.265Z,1540938623.265 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:23.266Z,1540938623.266 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6999
2018-10-30T22:30:23.749Z,1540938623.749 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-30T22:30:23.749Z,1540938623.749 [WetLabsBB2FL](INFO): Powering down
2018-10-30T22:30:23.750Z,1540938623.750 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:23.769Z,1540938623.769 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-10-30T22:30:23.769Z,1540938623.769 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:23.769Z,1540938623.769 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7000
2018-10-30T22:30:23.877Z,1540938623.877 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-30T22:30:23.877Z,1540938623.877 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:23.893Z,1540938623.893 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-30T22:30:23.893Z,1540938623.893 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:23.894Z,1540938623.894 [Radio_Surface](INFO): Join timeout helper Thread ID is 7001
2018-10-30T22:30:23.989Z,1540938623.989 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-30T22:30:23.989Z,1540938623.989 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:23.994Z,1540938623.994 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-30T22:30:23.994Z,1540938623.994 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:23.994Z,1540938623.994 [logger](INFO): Join timeout helper Thread ID is 7002
2018-10-30T22:30:24.040Z,1540938624.040 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-30T22:30:24.041Z,1540938624.041 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:24.053Z,1540938624.053 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-30T22:30:24.054Z,1540938624.054 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:24.054Z,1540938624.054 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-30T22:30:24.054Z,1540938624.054 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:24.054Z,1540938624.054 [controlThread](INFO): Join timeout helper Thread ID is 7003
2018-10-30T22:30:24.397Z,1540938624.397 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-30T22:30:24.397Z,1540938624.397 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-30T22:30:24.398Z,1540938624.398 [NAL9602](INFO): Powering down
2018-10-30T22:30:24.399Z,1540938624.399 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-30T22:30:24.400Z,1540938624.400 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-30T22:30:24.400Z,1540938624.400 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-30T22:30:24.401Z,1540938624.401 [MissionManager](INFO): Uninitializing Mission Default
2018-10-30T22:30:24.401Z,1540938624.401 [Default] Stopped
2018-10-30T22:30:24.401Z,1540938624.401 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-30T22:30:24.401Z,1540938624.401 [Default:B.GoToSurface] Stopped
2018-10-30T22:30:24.401Z,1540938624.401 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-30T22:30:24.401Z,1540938624.401 [Default:CheckIn] Stopped
2018-10-30T22:30:24.401Z,1540938624.401 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-30T22:30:24.402Z,1540938624.402 [Default:CheckIn:C.Wait] Stopped
2018-10-30T22:30:24.402Z,1540938624.402 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-30T22:30:24.405Z,1540938624.405 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-30T22:30:24.405Z,1540938624.405 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-30T22:30:24.405Z,1540938624.405 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-30T22:30:24.405Z,1540938624.405 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-30T22:30:24.405Z,1540938624.405 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-30T22:30:24.406Z,1540938624.406 [BuoyancyServo](INFO): Powering down
2018-10-30T22:30:24.417Z,1540938624.417 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-30T22:30:24.417Z,1540938624.417 [ElevatorServo](INFO): Powering down
2018-10-30T22:30:24.418Z,1540938624.418 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-30T22:30:24.418Z,1540938624.418 [MassServo](INFO): Powering down
2018-10-30T22:30:24.419Z,1540938624.419 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-30T22:30:24.419Z,1540938624.419 [RudderServo](INFO): Powering down
2018-10-30T22:30:24.420Z,1540938624.420 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-30T22:30:24.420Z,1540938624.420 [ThrusterServo](INFO): Powering down
2018-10-30T22:30:24.421Z,1540938624.421 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-30T22:30:24.421Z,1540938624.421 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-30T22:30:24.421Z,1540938624.421 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-30T22:30:24.422Z,1540938624.422 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:24.497Z,1540938624.497 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:24.546Z,1540938624.546 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:24.548Z,1540938624.548 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:24.595Z,1540938624.595 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-30T22:30:24.649Z,1540938624.649 [logger ThreadHandler](INFO): Thread cancelled.