2018-11-04T04:38:25.546Z,1541306305.546 [Supervisor](DEBUG): Initializing supervisor.
2018-11-04T04:38:25.549Z,1541306305.549 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-04T04:38:25.549Z,1541306305.549 [SyncHandler](INFO): Protected caller Thread ID is 5827
2018-11-04T04:38:25.550Z,1541306305.550 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-04T04:38:25.551Z,1541306305.551 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-04T04:38:25.551Z,1541306305.551 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5828
2018-11-04T04:38:25.554Z,1541306305.554 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-04T04:38:25.567Z,1541306305.567 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-04T04:38:25.568Z,1541306305.568 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-04T04:38:25.568Z,1541306305.568 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5829
2018-11-04T04:38:25.569Z,1541306305.569 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-04T04:38:25.570Z,1541306305.570 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-04T04:38:25.570Z,1541306305.570 [logger ThreadHandler](INFO): Protected caller Thread ID is 5830
2018-11-04T04:38:25.572Z,1541306305.572 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-04T04:38:25.573Z,1541306305.573 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-04T04:38:25.574Z,1541306305.574 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-04T04:38:26.070Z,1541306306.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-04T04:38:26.071Z,1541306306.071 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-04T04:38:26.534Z,1541306306.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-04T04:38:26.535Z,1541306306.535 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-04T04:38:26.736Z,1541306306.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-04T04:38:26.736Z,1541306306.736 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-04T04:38:26.884Z,1541306306.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-04T04:38:26.885Z,1541306306.885 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-04T04:38:27.340Z,1541306307.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-04T04:38:27.340Z,1541306307.340 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-04T04:38:27.437Z,1541306307.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-04T04:38:27.438Z,1541306307.438 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-04T04:38:27.581Z,1541306307.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-04T04:38:27.582Z,1541306307.582 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-04T04:38:27.684Z,1541306307.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-04T04:38:27.685Z,1541306307.685 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-04T04:38:27.789Z,1541306307.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-04T04:38:27.790Z,1541306307.790 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-04T04:38:28.147Z,1541306308.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-04T04:38:28.148Z,1541306308.148 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-04T04:38:28.356Z,1541306308.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-04T04:38:28.357Z,1541306308.357 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-04T04:38:28.576Z,1541306308.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-04T04:38:28.576Z,1541306308.576 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-04T04:38:28.657Z,1541306308.657 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-04T04:38:29.025Z,1541306309.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-04T04:38:29.026Z,1541306309.026 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-04T04:38:29.357Z,1541306309.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-04T04:38:29.359Z,1541306309.359 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-11-04T04:38:29.360Z,1541306309.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-11-04T04:38:29.445Z,1541306309.445 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-11-04T04:38:29.600Z,1541306309.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-11-04T04:38:29.699Z,1541306309.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-11-04T04:38:29.950Z,1541306309.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-11-04T04:38:30.035Z,1541306310.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-11-04T04:38:30.320Z,1541306310.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-04T04:38:30.321Z,1541306310.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-11-04T04:38:30.761Z,1541306310.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-11-04T04:38:30.865Z,1541306310.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-11-04T04:38:31.015Z,1541306311.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-11-04T04:38:31.119Z,1541306311.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-11-04T04:38:31.232Z,1541306311.232 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-04T04:38:31.239Z,1541306311.239 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-04T04:38:31.284Z,1541306311.284 [DepthRateCalculator] Loaded
2018-11-04T04:38:31.284Z,1541306311.284 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-04T04:38:31.290Z,1541306311.290 [PitchRateCalculator] Loaded
2018-11-04T04:38:31.290Z,1541306311.290 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-04T04:38:31.306Z,1541306311.306 [SpeedCalculator] Loaded
2018-11-04T04:38:31.307Z,1541306311.307 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-04T04:38:31.328Z,1541306311.328 [TempGradientCalculator] Loaded
2018-11-04T04:38:31.328Z,1541306311.328 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-04T04:38:31.334Z,1541306311.334 [YawRateCalculator] Loaded
2018-11-04T04:38:31.334Z,1541306311.334 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-04T04:38:31.375Z,1541306311.375 [ElevatorOffsetCalculator] Loaded
2018-11-04T04:38:31.375Z,1541306311.375 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-04T04:38:31.376Z,1541306311.376 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-04T04:38:31.376Z,1541306311.376 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-04T04:38:31.625Z,1541306311.625 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-04T04:38:31.626Z,1541306311.626 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-04T04:38:31.942Z,1541306311.942 [DataOverHttps] Loaded
2018-11-04T04:38:31.942Z,1541306311.942 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-04T04:38:31.968Z,1541306311.968 [Depth_Keller] Loaded
2018-11-04T04:38:31.968Z,1541306311.968 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-04T04:38:31.973Z,1541306311.973 [DropWeight] Loaded
2018-11-04T04:38:31.973Z,1541306311.973 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-04T04:38:32.073Z,1541306312.073 [NAL9602] Loaded
2018-11-04T04:38:32.073Z,1541306312.073 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-04T04:38:32.089Z,1541306312.089 [Onboard] Loaded
2018-11-04T04:38:32.090Z,1541306312.090 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-04T04:38:32.100Z,1541306312.100 [Radio_Surface] Loaded
2018-11-04T04:38:32.100Z,1541306312.100 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-04T04:38:32.102Z,1541306312.102 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-11-04T04:38:32.102Z,1541306312.102 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5910
2018-11-04T04:38:32.109Z,1541306312.109 [SCPI] Loaded
2018-11-04T04:38:32.109Z,1541306312.109 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-11-04T04:38:33.886Z,1541306313.886 [BPC1] Loaded
2018-11-04T04:38:33.886Z,1541306313.886 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-04T04:38:33.886Z,1541306313.886 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-04T04:38:33.887Z,1541306313.887 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-04T04:38:33.957Z,1541306313.957 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-04T04:38:33.958Z,1541306313.958 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-04T04:38:34.056Z,1541306314.056 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-11-04T04:38:34.056Z,1541306314.056 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-11-04T04:38:34.111Z,1541306314.111 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-04T04:38:34.111Z,1541306314.111 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-04T04:38:34.128Z,1541306314.128 [NavChart] Loaded
2018-11-04T04:38:34.128Z,1541306314.128 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-04T04:38:34.132Z,1541306314.132 [UniversalFixResidualReporter] Loaded
2018-11-04T04:38:34.132Z,1541306314.132 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-04T04:38:34.133Z,1541306314.133 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-04T04:38:34.134Z,1541306314.134 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-04T04:38:34.366Z,1541306314.366 [CTD_NeilBrown] Loaded
2018-11-04T04:38:34.366Z,1541306314.366 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-04T04:38:34.367Z,1541306314.367 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-11-04T04:38:34.368Z,1541306314.368 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5911
2018-11-04T04:38:34.418Z,1541306314.418 [WetLabsBB2FL] Loaded
2018-11-04T04:38:34.418Z,1541306314.418 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-04T04:38:34.419Z,1541306314.419 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-11-04T04:38:34.420Z,1541306314.420 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5912
2018-11-04T04:38:34.421Z,1541306314.421 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-04T04:38:34.421Z,1541306314.421 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-04T04:38:34.491Z,1541306314.491 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-04T04:38:34.592Z,1541306314.592 [VerticalControl] Loaded
2018-11-04T04:38:34.592Z,1541306314.592 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-04T04:38:34.593Z,1541306314.593 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-04T04:38:34.664Z,1541306314.664 [HorizontalControl] Loaded
2018-11-04T04:38:34.664Z,1541306314.664 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-04T04:38:34.664Z,1541306314.664 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-04T04:38:34.670Z,1541306314.670 [SpeedControl] Loaded
2018-11-04T04:38:34.670Z,1541306314.670 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-04T04:38:34.671Z,1541306314.671 [LoopControl](DEBUG): Construct LoopControl.
2018-11-04T04:38:34.671Z,1541306314.671 [LoopControl] Loaded
2018-11-04T04:38:34.672Z,1541306314.672 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-04T04:38:34.672Z,1541306314.672 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-04T04:38:34.673Z,1541306314.673 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-04T04:38:34.686Z,1541306314.686 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-04T04:38:34.687Z,1541306314.687 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-04T04:38:34.822Z,1541306314.822 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-04T04:38:34.833Z,1541306314.833 [SBIT] Loaded
2018-11-04T04:38:34.833Z,1541306314.833 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-04T04:38:34.834Z,1541306314.834 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-04T04:38:34.845Z,1541306314.845 [IBIT] Loaded
2018-11-04T04:38:34.846Z,1541306314.846 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-04T04:38:34.849Z,1541306314.849 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-04T04:38:35.002Z,1541306315.002 [CBIT] Loaded
2018-11-04T04:38:35.002Z,1541306315.002 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-04T04:38:35.002Z,1541306315.002 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-04T04:38:35.003Z,1541306315.003 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-04T04:38:35.108Z,1541306315.108 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-04T04:38:35.109Z,1541306315.109 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-04T04:38:35.135Z,1541306315.135 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-04T04:38:35.136Z,1541306315.136 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-04T04:38:35.236Z,1541306315.236 [BuoyancyServo] Loaded
2018-11-04T04:38:35.236Z,1541306315.236 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-04T04:38:35.248Z,1541306315.248 [ElevatorServo] Loaded
2018-11-04T04:38:35.248Z,1541306315.248 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-04T04:38:35.259Z,1541306315.259 [MassServo] Loaded
2018-11-04T04:38:35.260Z,1541306315.260 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-04T04:38:35.271Z,1541306315.271 [RudderServo] Loaded
2018-11-04T04:38:35.272Z,1541306315.272 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-04T04:38:35.283Z,1541306315.283 [ThrusterServo] Loaded
2018-11-04T04:38:35.283Z,1541306315.283 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-04T04:38:35.283Z,1541306315.283 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-04T04:38:35.326Z,1541306315.326 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-04T04:38:35.328Z,1541306315.328 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-04T04:38:35.334Z,1541306315.334 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-04T04:38:35.335Z,1541306315.335 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2018-11-04T04:38:35.336Z,1541306315.336 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5913
2018-11-04T04:38:35.341Z,1541306315.341 [Supervisor](INFO): Main Thread ID is 5826
2018-11-04T04:38:35.341Z,1541306315.341 [Supervisor](DEBUG): Running supervisor.
2018-11-04T04:38:35.342Z,1541306315.342 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5914
2018-11-04T04:38:35.344Z,1541306315.344 [controlThread ThreadHandler](INFO): Handler Thread ID is 5915
2018-11-04T04:38:35.345Z,1541306315.345 [controlThread](DEBUG): Initializing ControlThread
2018-11-04T04:38:35.346Z,1541306315.346 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-04T04:38:35.346Z,1541306315.346 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-04T04:38:35.346Z,1541306315.346 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-04T04:38:35.347Z,1541306315.347 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-04T04:38:35.347Z,1541306315.347 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-04T04:38:35.348Z,1541306315.348 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-04T04:38:35.355Z,1541306315.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-04T04:38:35.356Z,1541306315.356 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-04T04:38:35.357Z,1541306315.357 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-04T04:38:35.358Z,1541306315.358 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-04T04:38:35.358Z,1541306315.358 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-04T04:38:35.359Z,1541306315.359 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-04T04:38:35.359Z,1541306315.359 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-04T04:38:35.359Z,1541306315.359 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-04T04:38:35.360Z,1541306315.360 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-04T04:38:35.369Z,1541306315.369 [logger ThreadHandler](INFO): Handler Thread ID is 5916
2018-11-04T04:38:35.393Z,1541306315.393 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5917
2018-11-04T04:38:35.400Z,1541306315.400 [Radio_Surface](INFO): Powering up
2018-11-04T04:38:35.402Z,1541306315.402 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5919
2018-11-04T04:38:35.403Z,1541306315.403 [WetLabsBB2FL](INFO): Powering down
2018-11-04T04:38:35.423Z,1541306315.423 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5920
2018-11-04T04:38:35.427Z,1541306315.427 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-04T04:38:35.427Z,1541306315.427 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-04T04:38:35.428Z,1541306315.428 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-04T04:38:35.428Z,1541306315.428 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-04T04:38:35.428Z,1541306315.428 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-04T04:38:35.428Z,1541306315.428 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-04T04:38:35.428Z,1541306315.428 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-04T04:38:35.433Z,1541306315.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-04T04:38:35.433Z,1541306315.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-04T04:38:35.433Z,1541306315.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-04T04:38:35.433Z,1541306315.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-04T04:38:35.434Z,1541306315.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-04T04:38:35.434Z,1541306315.434 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-04T04:38:35.434Z,1541306315.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-04T04:38:35.434Z,1541306315.434 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-04T04:38:35.434Z,1541306315.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-04T04:38:35.440Z,1541306315.440 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-04T04:38:35.440Z,1541306315.440 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-04T04:38:35.441Z,1541306315.441 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5918
2018-11-04T04:38:35.442Z,1541306315.442 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-04T04:38:35.442Z,1541306315.442 [SBIT](INFO): Initialize SBIT Component.
2018-11-04T04:38:35.443Z,1541306315.443 [SBIT](IMPORTANT): git: 2018-11-02
2018-11-04T04:38:35.443Z,1541306315.443 [SBIT](INFO): git hash: 766718804c5d96cf896f07eddb44b232c8585615
2018-11-04T04:38:35.445Z,1541306315.445 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-04T04:38:35.445Z,1541306315.445 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-04T04:38:35.447Z,1541306315.447 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-11-04T04:38:35.448Z,1541306315.448 [IBIT](INFO): Initialize IBIT Component.
2018-11-04T04:38:35.465Z,1541306315.465 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-04T04:38:35.465Z,1541306315.465 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-04T04:38:35.575Z,1541306315.575 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-04T04:38:35.586Z,1541306315.586 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-04T04:38:35.614Z,1541306315.614 [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-11-04T04:38:35.614Z,1541306315.614 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-04T04:38:35.699Z,1541306315.699 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-04T04:38:35.700Z,1541306315.700 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-04T04:38:35.710Z,1541306315.710 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-04T04:38:35.734Z,1541306315.734 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-04T04:38:35.757Z,1541306315.757 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-04T04:38:35.763Z,1541306315.763 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-04T04:38:35.783Z,1541306315.783 [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-11-04T04:38:35.788Z,1541306315.788 [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-11-04T04:38:35.813Z,1541306315.813 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-04T04:38:35.869Z,1541306315.869 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2018-11-04T04:38:36.041Z,1541306316.041 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-04T04:38:36.241Z,1541306316.241 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-04T04:38:36.249Z,1541306316.249 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-04T04:38:36.271Z,1541306316.271 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-04T04:38:36.277Z,1541306316.277 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-04T04:38:36.299Z,1541306316.299 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-04T04:38:36.305Z,1541306316.305 [MassServo](DEBUG): Initializing MassServo.
2018-11-04T04:38:36.311Z,1541306316.311 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-04T04:38:36.317Z,1541306316.317 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-04T04:38:36.323Z,1541306316.323 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-04T04:38:36.329Z,1541306316.329 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-04T04:38:36.506Z,1541306316.506 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-04T04:38:36.506Z,1541306316.506 [DropWeight] Hardware Fault, FailCount= 1
2018-11-04T04:38:36.506Z,1541306316.506 [DropWeight](ERROR): Hardware Fault
2018-11-04T04:38:36.529Z,1541306316.529 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-04T04:38:36.562Z,1541306316.562 [CommandLine](FAULT): Scheduling is paused
2018-11-04T04:38:36.563Z,1541306316.563 [CBIT](INFO): Critical error at 20181104T043836
2018-11-04T04:38:36.563Z,1541306316.563 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-04T04:38:36.565Z,1541306316.565 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-04T04:38:36.566Z,1541306316.566 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-04T04:38:45.535Z,1541306325.535 [CBIT](INFO): Critical error at 20181104T043836
2018-11-04T04:38:46.351Z,1541306326.351 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004180
2018-11-04T04:39:02.394Z,1541306342.394 [NAL9602](INFO): Powering up NAL9602
2018-11-04T04:39:04.071Z,1541306344.071 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-04T04:39:04.075Z,1541306344.075 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-04T04:39:20.186Z,1541306360.186 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007488
CHAN A1 (24V): 0.001745
CHAN A2 (12V): -0.005397
CHAN A3 (5V): -0.002646
CHAN B0 (3.3V): -0.001110
CHAN B1 (3.15aV): -0.001690
CHAN B2 (3.15bV): -0.001305
CHAN B3 (GND): -0.000403
OPEN: -0.001185
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-04T04:39:27.401Z,1541306367.401 [CommandLine](IMPORTANT): got command failComponent
2018-11-04T04:39:27.402Z,1541306367.402 [CommandLine](IMPORTANT): Failed components:
2018-11-04T04:39:27.402Z,1541306367.402 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-11-04T04:39:27.634Z,1541306367.634 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-04T04:39:27.634Z,1541306367.634 [NAL9602] Communications Fault, FailCount= 1
2018-11-04T04:39:27.634Z,1541306367.634 [NAL9602](ERROR): Communications Fault
2018-11-04T04:39:27.731Z,1541306367.731 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-04T04:39:28.034Z,1541306368.034 [NAL9602](INFO): Powering down
2018-11-04T04:39:28.918Z,1541306368.918 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-04T04:39:28.918Z,1541306368.918 [NAL9602] No Fault, FailCount= 1
2018-11-04T04:39:56.654Z,1541306396.654 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2018-11-04T04:39:56.654Z,1541306396.654 [CommandLine](IMPORTANT): platform_buoyancy_position 951.756840 cc
2018-11-04T04:39:57.733Z,1541306397.733 [SBIT](IMPORTANT): SBIT PASSED
2018-11-04T04:39:57.754Z,1541306397.754 [CommandLine](IMPORTANT): got command configSet list
2018-11-04T04:39:57.754Z,1541306397.754 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-04T04:39:57.755Z,1541306397.755 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-11-04T04:39:57.755Z,1541306397.755 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-11-04T04:39:57.755Z,1541306397.755 [CommandLine](IMPORTANT): Depth_Keller.offset=2.34 decibar;

2018-11-04T04:39:57.756Z,1541306397.756 [CommandLine](IMPORTANT): Depth_Keller.scale=38.77 micropascal;

2018-11-04T04:39:57.756Z,1541306397.756 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=0 bool;

2018-11-04T04:39:58.077Z,1541306398.077 [NAL9602](INFO): Powering up NAL9602
2018-11-04T04:39:58.107Z,1541306398.107 [MissionManager](IMPORTANT): Started mission Startup
2018-11-04T04:39:58.107Z,1541306398.107 [Startup] Running Loop=1
2018-11-04T04:39:58.107Z,1541306398.107 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-04T04:39:58.107Z,1541306398.107 [Startup:A.GoToSurface] Running Loop=1
2018-11-04T04:39:58.107Z,1541306398.107 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-04T04:39:58.108Z,1541306398.108 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-04T04:39:58.108Z,1541306398.108 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-04T04:39:58.109Z,1541306398.109 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-04T04:39:58.109Z,1541306398.109 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-04T04:39:58.110Z,1541306398.110 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-04T04:39:58.116Z,1541306398.116 [Startup:StartupSatComms] Running Loop=1
2018-11-04T04:39:58.116Z,1541306398.116 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-11-04T04:39:58.116Z,1541306398.116 [Startup:StartupSatComms:A] Running Loop=1
2018-11-04T04:39:58.497Z,1541306398.497 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-11-04T04:40:19.270Z,1541306419.270 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-04T04:40:19.270Z,1541306419.270 [NAL9602] Communications Fault, FailCount= 2
2018-11-04T04:40:19.270Z,1541306419.270 [NAL9602](ERROR): Communications Fault
2018-11-04T04:40:19.346Z,1541306419.346 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-04T04:40:19.670Z,1541306419.670 [NAL9602](INFO): Powering down
2018-11-04T04:40:20.518Z,1541306420.518 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-04T04:40:20.518Z,1541306420.518 [NAL9602] No Fault, FailCount= 2
2018-11-04T04:40:27.371Z,1541306427.371 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 850.000000 cubic_centimeter
2018-11-04T04:40:27.372Z,1541306427.372 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2018-11-04T04:40:27.436Z,1541306427.436 [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,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-04T04:40:35.379Z,1541306435.379 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-04T04:40:35.379Z,1541306435.379 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-11-04T04:40:35.379Z,1541306435.379 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-04T04:40:35.381Z,1541306435.381 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-04T04:40:35.381Z,1541306435.381 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-11-04T04:40:35.381Z,1541306435.381 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-04T04:40:35.427Z,1541306435.427 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-04T04:40:35.427Z,1541306435.427 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-04T04:40:35.840Z,1541306435.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-04T04:40:35.840Z,1541306435.840 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-11-04T04:40:35.840Z,1541306435.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-04T04:40:35.840Z,1541306435.840 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-11-04T04:40:36.175Z,1541306436.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-04T04:40:36.176Z,1541306436.176 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-04T04:40:36.176Z,1541306436.176 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-04T04:40:36.176Z,1541306436.176 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-04T04:40:36.177Z,1541306436.177 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-04T04:40:36.177Z,1541306436.177 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-04T04:40:48.654Z,1541306448.654 [CommandLine](IMPORTANT): got command report touch platform_buoyancy_position
2018-11-04T04:40:49.750Z,1541306449.750 [NAL9602](INFO): Powering up NAL9602
2018-11-04T04:41:00.154Z,1541306460.154 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-04T04:39:58.1Z
2018-11-04T04:41:00.154Z,1541306460.154 [Startup:StartupSatComms:A] Stopped
2018-11-04T04:41:00.154Z,1541306460.154 [Startup:StartupSatComms:B] Running Loop=1
2018-11-04T04:41:00.270Z,1541306460.270 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-04T04:41:03.822Z,1541306463.822 [CommandLine](IMPORTANT): got command maintain clear
2018-11-04T04:41:05.094Z,1541306465.094 [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-11-04T04:41:05.103Z,1541306465.103 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20181102T204111/Courier0130.lzma
2018-11-04T04:41:05.904Z,1541306465.904 [DataOverHttps](INFO): Moved sent file to Logs/20181102T204111/Courier0130.lzma.bak
2018-11-04T04:41:05.904Z,1541306465.904 [DataOverHttps](INFO): SBD MOMSN=8791169
2018-11-04T04:41:10.634Z,1541306470.634 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-04T04:41:10.634Z,1541306470.634 [NAL9602] Communications Fault, FailCount= 3
2018-11-04T04:41:10.634Z,1541306470.634 [NAL9602](ERROR): Communications Fault
2018-11-04T04:41:10.738Z,1541306470.738 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-04T04:41:10.740Z,1541306470.740 [Reporter](INFO): platform_buoyancy_position 859.224121 cc
2018-11-04T04:41:11.026Z,1541306471.026 [NAL9602](INFO): Powering down
2018-11-04T04:41:11.111Z,1541306471.111 [Reporter](INFO): platform_buoyancy_position 859.224121 cc
2018-11-04T04:41:11.495Z,1541306471.495 [Reporter](INFO): platform_buoyancy_position 859.224121 cc
2018-11-04T04:41:14.706Z,1541306474.706 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-04T04:41:14.706Z,1541306474.706 [NAL9602] No Fault, FailCount= 3
2018-11-04T04:41:14.708Z,1541306474.708 [Reporter](INFO): platform_buoyancy_position 867.953757 cc
2018-11-04T04:41:14.718Z,1541306474.718 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181104T043825/Courier0000.lzma
2018-11-04T04:41:14.819Z,1541306474.819 [Reporter](INFO): platform_buoyancy_position 867.953757 cc
2018-11-04T04:41:15.202Z,1541306475.202 [Reporter](INFO): platform_buoyancy_position 869.968149 cc
2018-11-04T04:41:15.519Z,1541306475.519 [DataOverHttps](INFO): Moved sent file to Logs/20181104T043825/Courier0000.lzma.bak
2018-11-04T04:41:15.519Z,1541306475.519 [DataOverHttps](INFO): SBD MOMSN=8791172
2018-11-04T04:41:15.612Z,1541306475.612 [Reporter](INFO): platform_buoyancy_position 871.982658 cc
2018-11-04T04:41:16.067Z,1541306476.067 [Reporter](INFO): platform_buoyancy_position 872.385630 cc
2018-11-04T04:41:16.411Z,1541306476.411 [Reporter](INFO): platform_buoyancy_position 874.265737 cc
2018-11-04T04:41:19.607Z,1541306479.607 [Reporter](INFO): platform_buoyancy_position 885.009766 cc
2018-11-04T04:41:19.736Z,1541306479.736 [Reporter](INFO): platform_buoyancy_position 885.144051 cc
2018-11-04T04:41:20.131Z,1541306480.131 [Reporter](INFO): platform_buoyancy_position 886.352791 cc
2018-11-04T04:41:20.520Z,1541306480.520 [Reporter](INFO): platform_buoyancy_position 887.427130 cc
2018-11-04T04:41:20.935Z,1541306480.935 [Reporter](INFO): platform_buoyancy_position 889.441639 cc
2018-11-04T04:41:21.307Z,1541306481.307 [Reporter](INFO): platform_buoyancy_position 889.441639 cc
2018-11-04T04:41:24.376Z,1541306484.376 [Reporter](INFO): platform_buoyancy_position 900.051382 cc
2018-11-04T04:41:24.388Z,1541306484.388 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20181102T204111/Express0131.lzma
2018-11-04T04:41:24.495Z,1541306484.495 [Reporter](INFO): platform_buoyancy_position 900.185667 cc
2018-11-04T04:41:24.880Z,1541306484.880 [Reporter](INFO): platform_buoyancy_position 902.334461 cc
2018-11-04T04:41:25.187Z,1541306485.187 [DataOverHttps](INFO): Moved sent file to Logs/20181102T204111/Express0131.lzma.bak
2018-11-04T04:41:25.188Z,1541306485.188 [DataOverHttps](INFO): SBD MOMSN=8791177
2018-11-04T04:41:25.284Z,1541306485.284 [Reporter](INFO): platform_buoyancy_position 904.348970 cc
2018-11-04T04:41:25.747Z,1541306485.747 [Reporter](INFO): platform_buoyancy_position 904.751825 cc
2018-11-04T04:41:26.067Z,1541306486.067 [Reporter](INFO): platform_buoyancy_position 906.497822 cc
2018-11-04T04:41:29.140Z,1541306489.140 [Reporter](INFO): platform_buoyancy_position 915.227283 cc
2018-11-04T04:41:29.248Z,1541306489.248 [Reporter](INFO): platform_buoyancy_position 916.570309 cc
2018-11-04T04:41:29.645Z,1541306489.645 [Reporter](INFO): platform_buoyancy_position 917.376077 cc
2018-11-04T04:41:30.044Z,1541306490.044 [Reporter](INFO): platform_buoyancy_position 919.390644 cc
2018-11-04T04:41:30.431Z,1541306490.431 [Reporter](INFO): platform_buoyancy_position 919.390644 cc
2018-11-04T04:41:30.843Z,1541306490.843 [Reporter](INFO): platform_buoyancy_position 921.539438 cc
2018-11-04T04:41:34.047Z,1541306494.047 [Reporter](INFO): platform_buoyancy_position 932.283350 cc
2018-11-04T04:41:34.059Z,1541306494.059 [DataOverHttps](INFO): Sending 1022 bytes from file Logs/20181104T043825/Express0001.lzma
2018-11-04T04:41:34.196Z,1541306494.196 [Reporter](INFO): platform_buoyancy_position 932.417752 cc
2018-11-04T04:41:34.572Z,1541306494.572 [Reporter](INFO): platform_buoyancy_position 934.297859 cc
2018-11-04T04:41:34.855Z,1541306494.855 [DataOverHttps](INFO): Moved sent file to Logs/20181104T043825/Express0001.lzma.bak
2018-11-04T04:41:34.855Z,1541306494.855 [DataOverHttps](INFO): SBD MOMSN=8791185
2018-11-04T04:41:34.987Z,1541306494.987 [Reporter](INFO): platform_buoyancy_position 934.566546 cc
2018-11-04T04:41:35.365Z,1541306495.365 [Startup:StartupSatComms:B] Stopped
2018-11-04T04:41:35.365Z,1541306495.365 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-11-04T04:41:35.365Z,1541306495.365 [Startup:StartupSatComms] Stopped
2018-11-04T04:41:35.365Z,1541306495.365 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-11-04T04:41:35.366Z,1541306495.366 [Startup](INFO): Completed Startup
2018-11-04T04:41:35.366Z,1541306495.366 [MissionManager](INFO): Startup is completed.
2018-11-04T04:41:35.367Z,1541306495.367 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-04T04:41:35.367Z,1541306495.367 [Startup] Stopped
2018-11-04T04:41:35.367Z,1541306495.367 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-04T04:41:35.367Z,1541306495.367 [Startup:A.GoToSurface] Stopped
2018-11-04T04:41:35.367Z,1541306495.367 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-04T04:41:35.456Z,1541306495.456 [Reporter](INFO): platform_buoyancy_position 936.580938 cc
2018-11-04T04:41:35.695Z,1541306495.695 [MissionManager](IMPORTANT): Started mission Default
2018-11-04T04:41:35.695Z,1541306495.695 [Default] Running Loop=1
2018-11-04T04:41:35.695Z,1541306495.695 [Default](DEBUG): Aggregate::initialize Default
2018-11-04T04:41:35.696Z,1541306495.696 [Default:B.GoToSurface] Running Loop=1
2018-11-04T04:41:35.696Z,1541306495.696 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-04T04:41:35.696Z,1541306495.696 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-04T04:41:35.696Z,1541306495.696 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-04T04:41:35.697Z,1541306495.697 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-04T04:41:35.697Z,1541306495.697 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-04T04:41:35.698Z,1541306495.698 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-04T04:41:35.698Z,1541306495.698 [Default:A.Wait] Running Loop=1
2018-11-04T04:41:35.698Z,1541306495.698 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-11-04T04:41:35.766Z,1541306495.766 [Reporter](INFO): platform_buoyancy_position 936.983910 cc
2018-11-04T04:41:38.817Z,1541306498.817 [Reporter](INFO): platform_buoyancy_position 947.324967 cc
2018-11-04T04:41:41.234Z,1541306501.234 [NAL9602](INFO): Powering up NAL9602
2018-11-04T04:41:48.863Z,1541306508.863 [Default:A.Wait](INFO): Done Waiting.
2018-11-04T04:41:48.864Z,1541306508.864 [Default:A.Wait] Stopped
2018-11-04T04:41:48.864Z,1541306508.864 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-11-04T04:41:49.263Z,1541306509.263 [Default:CheckIn] Running Loop=1
2018-11-04T04:41:49.264Z,1541306509.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-04T04:41:49.264Z,1541306509.264 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-04T04:41:49.663Z,1541306509.663 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-11-04T04:42:02.434Z,1541306522.434 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-04T04:42:02.435Z,1541306522.435 [NAL9602] Communications Fault, FailCount= 4
2018-11-04T04:42:02.435Z,1541306522.435 [NAL9602](ERROR): Communications Fault
2018-11-04T04:42:02.517Z,1541306522.517 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-04T04:42:02.830Z,1541306522.830 [NAL9602](INFO): Powering down
2018-11-04T04:42:03.670Z,1541306523.670 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-04T04:42:03.670Z,1541306523.670 [NAL9602] No Fault, FailCount= 4
2018-11-04T04:42:18.687Z,1541306538.687 [CommandLine](IMPORTANT): got command report touch BuoyancyServo.component_avgCurrent
2018-11-04T04:42:33.034Z,1541306553.034 [NAL9602](INFO): Powering up NAL9602
2018-11-04T04:42:36.270Z,1541306556.270 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-04T04:42:36.270Z,1541306556.270 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-11-04T04:42:36.271Z,1541306556.271 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-04T04:42:36.272Z,1541306556.272 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-04T04:42:36.272Z,1541306556.272 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-11-04T04:42:36.272Z,1541306556.272 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-04T04:42:36.286Z,1541306556.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-04T04:42:36.286Z,1541306556.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-04T04:42:36.686Z,1541306556.686 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-04T04:42:36.686Z,1541306556.686 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-11-04T04:42:36.686Z,1541306556.686 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-04T04:42:36.686Z,1541306556.686 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-11-04T04:42:37.052Z,1541306557.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-04T04:42:37.053Z,1541306557.053 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-04T04:42:37.053Z,1541306557.053 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-04T04:42:37.054Z,1541306557.054 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-04T04:42:37.054Z,1541306557.054 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-04T04:42:37.054Z,1541306557.054 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-04T04:42:54.222Z,1541306574.222 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-04T04:42:54.222Z,1541306574.222 [NAL9602] Communications Fault, FailCount= 5
2018-11-04T04:42:54.222Z,1541306574.222 [NAL9602](ERROR): Communications Fault
2018-11-04T04:42:54.401Z,1541306574.401 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-04T04:42:54.402Z,1541306574.402 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-04T04:42:54.622Z,1541306574.622 [NAL9602](INFO): Powering down
2018-11-04T04:42:54.700Z,1541306574.700 [CBIT](INFO): Critical error at 20181104T044254
2018-11-04T04:43:06.226Z,1541306586.226 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2018-11-04T04:43:06.227Z,1541306586.227 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2018-11-04T04:43:06.303Z,1541306586.303 [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,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-04T04:43:10.727Z,1541306590.727 [Reporter](INFO): platform_buoyancy_position 958.068995 cc
2018-11-04T04:43:11.088Z,1541306591.088 [Reporter](INFO): platform_buoyancy_position 958.203222 cc
2018-11-04T04:43:11.495Z,1541306591.495 [Reporter](INFO): platform_buoyancy_position 956.054428 cc
2018-11-04T04:43:11.899Z,1541306591.899 [Reporter](INFO): platform_buoyancy_position 945.176173 cc
2018-11-04T04:43:11.900Z,1541306591.900 [Reporter](INFO): BuoyancyServo.component_avgCurrent 73.399998 mA
2018-11-04T04:43:12.288Z,1541306592.288 [Reporter](INFO): platform_buoyancy_position 943.296007 cc
2018-11-04T04:43:12.695Z,1541306592.695 [Reporter](INFO): platform_buoyancy_position 941.012928 cc
2018-11-04T04:43:13.101Z,1541306593.101 [Reporter](INFO): platform_buoyancy_position 936.715340 cc
2018-11-04T04:43:13.484Z,1541306593.484 [Reporter](INFO): platform_buoyancy_position 932.417752 cc
2018-11-04T04:43:13.895Z,1541306593.895 [Reporter](INFO): platform_buoyancy_position 926.105597 cc
2018-11-04T04:43:14.299Z,1541306594.299 [Reporter](INFO): platform_buoyancy_position 921.539438 cc
2018-11-04T04:43:14.300Z,1541306594.300 [Reporter](INFO): BuoyancyServo.component_avgCurrent 333.970010 mA
2018-11-04T04:43:14.692Z,1541306594.692 [Reporter](INFO): platform_buoyancy_position 917.241792 cc
2018-11-04T04:43:15.102Z,1541306595.102 [Reporter](INFO): platform_buoyancy_position 910.929695 cc
2018-11-04T04:43:15.495Z,1541306595.495 [Reporter](INFO): platform_buoyancy_position 906.497822 cc
2018-11-04T04:43:15.888Z,1541306595.888 [Reporter](INFO): platform_buoyancy_position 900.185667 cc
2018-11-04T04:43:16.291Z,1541306596.291 [Reporter](INFO): platform_buoyancy_position 895.753794 cc
2018-11-04T04:43:16.692Z,1541306596.692 [Reporter](INFO): platform_buoyancy_position 891.321921 cc
2018-11-04T04:43:16.692Z,1541306596.692 [Reporter](INFO): BuoyancyServo.component_avgCurrent 477.100015 mA
2018-11-04T04:43:17.096Z,1541306597.096 [Reporter](INFO): platform_buoyancy_position 885.278336 cc
2018-11-04T04:43:17.496Z,1541306597.496 [Reporter](INFO): platform_buoyancy_position 880.443607 cc
2018-11-04T04:43:17.897Z,1541306597.897 [Reporter](INFO): platform_buoyancy_position 874.400022 cc
2018-11-04T04:43:18.296Z,1541306598.296 [Reporter](INFO): platform_buoyancy_position 869.968149 cc
2018-11-04T04:43:18.699Z,1541306598.699 [Reporter](INFO): platform_buoyancy_position 863.924681 cc
2018-11-04T04:43:19.099Z,1541306599.099 [Reporter](INFO): platform_buoyancy_position 859.224121 cc
2018-11-04T04:43:19.100Z,1541306599.100 [Reporter](INFO): BuoyancyServo.component_avgCurrent 447.740018 mA
2018-11-04T04:43:19.492Z,1541306599.492 [Reporter](INFO): platform_buoyancy_position 853.449223 cc
2018-11-04T04:43:19.892Z,1541306599.892 [Reporter](INFO): platform_buoyancy_position 848.614494 cc
2018-11-04T04:43:20.307Z,1541306600.307 [Reporter](INFO): platform_buoyancy_position 844.182621 cc
2018-11-04T04:43:20.696Z,1541306600.696 [Reporter](INFO): platform_buoyancy_position 837.870466 cc
2018-11-04T04:43:21.155Z,1541306601.155 [Reporter](INFO): platform_buoyancy_position 832.632824 cc
2018-11-04T04:43:21.559Z,1541306601.559 [Reporter](INFO): platform_buoyancy_position 827.126438 cc
2018-11-04T04:43:21.560Z,1541306601.560 [Reporter](INFO): BuoyancyServo.component_avgCurrent 502.790034 mA
2018-11-04T04:43:24.611Z,1541306604.611 [Reporter](INFO): platform_buoyancy_position 786.299235 cc
2018-11-04T04:43:24.728Z,1541306604.728 [Reporter](INFO): platform_buoyancy_position 785.896380 cc
2018-11-04T04:43:25.119Z,1541306605.119 [Reporter](INFO): platform_buoyancy_position 779.852853 cc
2018-11-04T04:43:25.504Z,1541306605.504 [Reporter](INFO): platform_buoyancy_position 775.555323 cc
2018-11-04T04:43:25.913Z,1541306605.913 [Reporter](INFO): platform_buoyancy_position 769.108883 cc
2018-11-04T04:43:26.309Z,1541306606.309 [Reporter](INFO): platform_buoyancy_position 764.811295 cc
2018-11-04T04:43:26.310Z,1541306606.310 [Reporter](INFO): BuoyancyServo.component_avgCurrent 455.080003 mA
2018-11-04T04:43:26.711Z,1541306606.711 [Reporter](INFO): platform_buoyancy_position 760.513649 cc
2018-11-04T04:43:27.103Z,1541306607.103 [Reporter](INFO): platform_buoyancy_position 754.201610 cc
2018-11-04T04:43:27.513Z,1541306607.513 [Reporter](INFO): platform_buoyancy_position 749.635394 cc
2018-11-04T04:43:27.899Z,1541306607.899 [Reporter](INFO): platform_buoyancy_position 743.323239 cc
2018-11-04T04:43:28.311Z,1541306608.311 [Reporter](INFO): platform_buoyancy_position 738.891365 cc
2018-11-04T04:43:28.711Z,1541306608.711 [Reporter](INFO): platform_buoyancy_position 732.982182 cc
2018-11-04T04:43:28.712Z,1541306608.712 [Reporter](INFO): BuoyancyServo.component_avgCurrent 499.119997 mA
2018-11-04T04:43:29.103Z,1541306609.103 [Reporter](INFO): platform_buoyancy_position 728.281680 cc
2018-11-04T04:43:29.517Z,1541306609.517 [Reporter](INFO): platform_buoyancy_position 721.835240 cc
2018-11-04T04:43:29.920Z,1541306609.920 [Reporter](INFO): platform_buoyancy_position 717.537652 cc
2018-11-04T04:43:30.311Z,1541306610.311 [Reporter](INFO): platform_buoyancy_position 711.091270 cc
2018-11-04T04:43:30.711Z,1541306610.711 [Reporter](INFO): platform_buoyancy_position 706.659339 cc
2018-11-04T04:43:31.115Z,1541306611.115 [Reporter](INFO): platform_buoyancy_position 701.555982 cc
2018-11-04T04:43:31.116Z,1541306611.116 [Reporter](INFO): BuoyancyServo.component_avgCurrent 491.780013 mA
2018-11-04T04:43:31.495Z,1541306611.495 [Reporter](INFO): platform_buoyancy_position 695.915369 cc
2018-11-04T04:43:31.922Z,1541306611.922 [Reporter](INFO): platform_buoyancy_position 691.617723 cc
2018-11-04T04:43:32.315Z,1541306612.315 [Reporter](INFO): platform_buoyancy_position 685.305684 cc
2018-11-04T04:43:32.715Z,1541306612.715 [Reporter](INFO): platform_buoyancy_position 680.873753 cc
2018-11-04T04:43:33.115Z,1541306613.115 [Reporter](INFO): platform_buoyancy_position 674.427371 cc
2018-11-04T04:43:33.516Z,1541306613.516 [Reporter](INFO): platform_buoyancy_position 670.264009 cc
2018-11-04T04:43:33.517Z,1541306613.517 [Reporter](INFO): BuoyancyServo.component_avgCurrent 477.100015 mA
2018-11-04T04:43:33.900Z,1541306613.900 [Reporter](INFO): platform_buoyancy_position 663.951971 cc
2018-11-04T04:43:34.299Z,1541306614.299 [Reporter](INFO): platform_buoyancy_position 659.520098 cc
2018-11-04T04:43:34.708Z,1541306614.708 [Reporter](INFO): platform_buoyancy_position 654.819596 cc
2018-11-04T04:43:35.100Z,1541306615.100 [Reporter](INFO): platform_buoyancy_position 648.776069 cc
2018-11-04T04:43:35.500Z,1541306615.500 [Reporter](INFO): platform_buoyancy_position 644.478481 cc
2018-11-04T04:43:35.911Z,1541306615.911 [Reporter](INFO): platform_buoyancy_position 638.032041 cc
2018-11-04T04:43:35.912Z,1541306615.912 [Reporter](INFO): BuoyancyServo.component_avgCurrent 473.430037 mA
2018-11-04T04:43:36.311Z,1541306616.311 [Reporter](INFO): platform_buoyancy_position 633.600168 cc
2018-11-04T04:43:36.719Z,1541306616.719 [Reporter](INFO): platform_buoyancy_position 628.765381 cc
2018-11-04T04:43:37.172Z,1541306617.172 [Reporter](INFO): platform_buoyancy_position 622.856140 cc
2018-11-04T04:43:37.579Z,1541306617.579 [Reporter](INFO): platform_buoyancy_position 616.544043 cc
2018-11-04T04:43:37.935Z,1541306617.935 [Reporter](INFO): platform_buoyancy_position 612.112170 cc
2018-11-04T04:43:38.328Z,1541306618.328 [Reporter](INFO): platform_buoyancy_position 607.814582 cc
2018-11-04T04:43:38.328Z,1541306618.328 [Reporter](INFO): BuoyancyServo.component_avgCurrent 513.799965 mA
2018-11-04T04:43:38.727Z,1541306618.727 [Reporter](INFO): platform_buoyancy_position 601.368141 cc
2018-11-04T04:43:39.123Z,1541306619.123 [Reporter](INFO): platform_buoyancy_position 597.070553 cc
2018-11-04T04:43:39.528Z,1541306619.528 [Reporter](INFO): platform_buoyancy_position 590.892741 cc
2018-11-04T04:43:39.918Z,1541306619.918 [Reporter](INFO): platform_buoyancy_position 586.326525 cc
2018-11-04T04:43:40.328Z,1541306620.328 [Reporter](INFO): platform_buoyancy_position 580.014486 cc
2018-11-04T04:43:40.708Z,1541306620.708 [Reporter](INFO): platform_buoyancy_position 575.716840 cc
2018-11-04T04:43:40.708Z,1541306620.708 [Reporter](INFO): BuoyancyServo.component_avgCurrent 502.790034 mA
2018-11-04T04:43:41.120Z,1541306621.120 [Reporter](INFO): platform_buoyancy_position 571.016339 cc
2018-11-04T04:43:41.516Z,1541306621.516 [Reporter](INFO): platform_buoyancy_position 564.972812 cc
2018-11-04T04:43:41.908Z,1541306621.908 [Reporter](INFO): platform_buoyancy_position 560.406654 cc
2018-11-04T04:43:42.319Z,1541306622.319 [Reporter](INFO): platform_buoyancy_position 554.363185 cc
2018-11-04T04:43:42.723Z,1541306622.723 [Reporter](INFO): platform_buoyancy_position 549.796910 cc
2018-11-04T04:43:43.087Z,1541306623.087 [Reporter](INFO): platform_buoyancy_position 543.753500 cc
2018-11-04T04:43:43.088Z,1541306623.088 [Reporter](INFO): BuoyancyServo.component_avgCurrent 480.770022 mA
2018-11-04T04:43:43.500Z,1541306623.500 [Reporter](INFO): platform_buoyancy_position 539.187284 cc
2018-11-04T04:43:43.905Z,1541306623.905 [Reporter](INFO): platform_buoyancy_position 534.352497 cc
2018-11-04T04:43:44.296Z,1541306624.296 [Reporter](INFO): platform_buoyancy_position 528.577599 cc
2018-11-04T04:43:44.692Z,1541306624.692 [Reporter](INFO): platform_buoyancy_position 524.011324 cc
2018-11-04T04:43:45.105Z,1541306625.105 [Reporter](INFO): platform_buoyancy_position 517.699285 cc
2018-11-04T04:43:45.496Z,1541306625.496 [Reporter](INFO): platform_buoyancy_position 513.401697 cc
2018-11-04T04:43:45.496Z,1541306625.496 [Reporter](INFO): BuoyancyServo.component_avgCurrent 477.100015 mA
2018-11-04T04:43:45.901Z,1541306625.901 [Reporter](INFO): platform_buoyancy_position 506.820914 cc
2018-11-04T04:43:46.307Z,1541306626.307 [Reporter](INFO): platform_buoyancy_position 502.523384 cc
2018-11-04T04:43:46.696Z,1541306626.696 [Reporter](INFO): platform_buoyancy_position 496.211229 cc
2018-11-04T04:43:47.115Z,1541306627.115 [Reporter](INFO): platform_buoyancy_position 491.779356 cc
2018-11-04T04:43:47.523Z,1541306627.523 [Reporter](INFO): platform_buoyancy_position 487.616024 cc
2018-11-04T04:43:47.915Z,1541306627.915 [Reporter](INFO): platform_buoyancy_position 481.169671 cc
2018-11-04T04:43:47.916Z,1541306627.916 [Reporter](INFO): BuoyancyServo.component_avgCurrent 462.420017 mA
2018-11-04T04:43:48.315Z,1541306628.315 [Reporter](INFO): platform_buoyancy_position 476.737740 cc
2018-11-04T04:43:48.724Z,1541306628.724 [Reporter](INFO): platform_buoyancy_position 470.425672 cc
2018-11-04T04:43:49.116Z,1541306629.116 [Reporter](INFO): platform_buoyancy_position 465.993769 cc
2018-11-04T04:43:49.516Z,1541306629.516 [Reporter](INFO): platform_buoyancy_position 459.547358 cc
2018-11-04T04:43:49.924Z,1541306629.924 [Reporter](INFO): platform_buoyancy_position 455.249770 cc
2018-11-04T04:43:50.371Z,1541306630.371 [Reporter](INFO): platform_buoyancy_position 448.803359 cc
2018-11-04T04:43:50.372Z,1541306630.372 [Reporter](INFO): BuoyancyServo.component_avgCurrent 546.829998 mA
2018-11-04T04:43:50.776Z,1541306630.776 [Reporter](INFO): platform_buoyancy_position 444.505742 cc
2018-11-04T04:43:51.135Z,1541306631.135 [Reporter](INFO): platform_buoyancy_position 438.327930 cc
2018-11-04T04:43:51.531Z,1541306631.531 [Reporter](INFO): platform_buoyancy_position 433.761714 cc
2018-11-04T04:43:51.923Z,1541306631.923 [Reporter](INFO): platform_buoyancy_position 427.718274 cc
2018-11-04T04:43:52.319Z,1541306632.319 [Reporter](INFO): platform_buoyancy_position 423.152029 cc
2018-11-04T04:43:52.715Z,1541306632.715 [Reporter](INFO): platform_buoyancy_position 418.720156 cc
2018-11-04T04:43:52.716Z,1541306632.716 [Reporter](INFO): BuoyancyServo.component_avgCurrent 462.420017 mA
2018-11-04T04:43:53.113Z,1541306633.113 [Reporter](INFO): platform_buoyancy_position 412.273745 cc
2018-11-04T04:43:53.513Z,1541306633.513 [Reporter](INFO): platform_buoyancy_position 407.976127 cc
2018-11-04T04:43:53.915Z,1541306633.915 [Reporter](INFO): platform_buoyancy_position 401.664060 cc
2018-11-04T04:43:54.284Z,1541306634.284 [Reporter](INFO): platform_buoyancy_position 397.366442 cc
2018-11-04T04:43:54.718Z,1541306634.718 [Reporter](INFO): platform_buoyancy_position 391.188660 cc
2018-11-04T04:43:55.087Z,1541306635.087 [Reporter](INFO): platform_buoyancy_position 386.622443 cc
2018-11-04T04:43:55.088Z,1541306635.088 [Reporter](INFO): BuoyancyServo.component_avgCurrent 455.080003 mA
2018-11-04T04:43:55.499Z,1541306635.499 [Reporter](INFO): platform_buoyancy_position 382.190541 cc
2018-11-04T04:43:55.887Z,1541306635.887 [Reporter](INFO): platform_buoyancy_position 375.878415 cc
2018-11-04T04:43:56.299Z,1541306636.299 [Reporter](INFO): platform_buoyancy_position 371.580885 cc
2018-11-04T04:43:56.696Z,1541306636.695 [Reporter](INFO): platform_buoyancy_position 365.268730 cc
2018-11-04T04:43:59.800Z,1541306639.800 [Reporter](INFO): platform_buoyancy_position 326.321722 cc
2018-11-04T04:43:59.801Z,1541306639.801 [Reporter](INFO): BuoyancyServo.component_avgCurrent 480.770022 mA
2018-11-04T04:43:59.904Z,1541306639.904 [Reporter](INFO): platform_buoyancy_position 324.307242 cc
2018-11-04T04:44:00.292Z,1541306640.292 [Reporter](INFO): platform_buoyancy_position 317.860831 cc
2018-11-04T04:44:00.707Z,1541306640.707 [Reporter](INFO): platform_buoyancy_position 313.428958 cc
2018-11-04T04:44:01.092Z,1541306641.092 [Reporter](INFO): platform_buoyancy_position 309.265626 cc
2018-11-04T04:44:01.495Z,1541306641.495 [Reporter](INFO): platform_buoyancy_position 304.968038 cc
2018-11-04T04:44:01.899Z,1541306641.899 [Reporter](INFO): platform_buoyancy_position 298.521627 cc
2018-11-04T04:44:01.900Z,1541306641.900 [Reporter](INFO): BuoyancyServo.component_avgCurrent 495.450020 mA
2018-11-04T04:44:02.303Z,1541306642.303 [Reporter](INFO): platform_buoyancy_position 294.089725 cc
2018-11-04T04:44:02.704Z,1541306642.704 [Reporter](INFO): platform_buoyancy_position 287.777657 cc
2018-11-04T04:44:03.107Z,1541306643.107 [Reporter](INFO): platform_buoyancy_position 283.345726 cc
2018-11-04T04:44:03.552Z,1541306643.552 [Reporter](INFO): platform_buoyancy_position 276.899314 cc
2018-11-04T04:44:03.964Z,1541306643.964 [Reporter](INFO): platform_buoyancy_position 272.601756 cc
2018-11-04T04:44:04.311Z,1541306644.311 [Reporter](INFO): platform_buoyancy_position 266.961113 cc
2018-11-04T04:44:04.312Z,1541306644.312 [Reporter](INFO): BuoyancyServo.component_avgCurrent 513.799965 mA
2018-11-04T04:44:04.704Z,1541306644.704 [Reporter](INFO): platform_buoyancy_position 261.992041 cc
2018-11-04T04:44:05.100Z,1541306645.100 [Reporter](INFO): platform_buoyancy_position 257.560110 cc
2018-11-04T04:44:05.503Z,1541306645.503 [Reporter](INFO): platform_buoyancy_position 252.591039 cc
2018-11-04T04:44:05.899Z,1541306645.899 [Reporter](INFO): platform_buoyancy_position 246.950425 cc
2018-11-04T04:44:06.313Z,1541306646.313 [Reporter](INFO): platform_buoyancy_position 241.981325 cc
2018-11-04T04:44:06.699Z,1541306646.699 [Reporter](INFO): platform_buoyancy_position 236.206426 cc
2018-11-04T04:44:06.700Z,1541306646.700 [Reporter](INFO): BuoyancyServo.component_avgCurrent 524.810016 mA
2018-11-04T04:44:07.099Z,1541306647.099 [Reporter](INFO): platform_buoyancy_position 231.774538 cc
2018-11-04T04:44:07.496Z,1541306647.496 [Reporter](INFO): platform_buoyancy_position 225.462427 cc
2018-11-04T04:44:07.889Z,1541306647.889 [Reporter](INFO): platform_buoyancy_position 221.030525 cc
2018-11-04T04:44:08.299Z,1541306648.299 [Reporter](INFO): platform_buoyancy_position 214.584143 cc
2018-11-04T04:44:11.941Z,1541306651.941 [Reporter](INFO): platform_buoyancy_position 202.228519 cc
2018-11-04T04:44:11.942Z,1541306651.942 [Reporter](INFO): BuoyancyServo.component_avgCurrent 543.160021 mA
2018-11-04T04:44:13.989Z,1541306653.989 [Reporter](INFO): BuoyancyServo.component_avgCurrent 0.000000 mA
2018-11-04T04:44:37.119Z,1541306677.119 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-04T04:44:37.119Z,1541306677.119 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-11-04T04:44:37.119Z,1541306677.119 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-11-04T04:44:37.121Z,1541306677.121 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-04T04:44:37.121Z,1541306677.121 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-11-04T04:44:37.121Z,1541306677.121 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-04T04:44:37.134Z,1541306677.134 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-11-04T04:44:37.135Z,1541306677.135 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-04T04:44:37.534Z,1541306677.534 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-11-04T04:44:37.534Z,1541306677.534 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-11-04T04:44:37.534Z,1541306677.534 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-04T04:44:37.534Z,1541306677.534 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-11-04T04:44:37.928Z,1541306677.928 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-04T04:44:37.929Z,1541306677.929 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-04T04:44:37.929Z,1541306677.929 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-04T04:44:37.930Z,1541306677.930 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-04T04:44:37.930Z,1541306677.930 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-04T04:44:37.930Z,1541306677.930 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-04T04:44:39.418Z,1541306679.418 [CommandLine](IMPORTANT): got command maintain clear
2018-11-04T04:44:39.545Z,1541306679.545 [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-11-04T04:44:44.063Z,1541306684.063 [Reporter](INFO): platform_buoyancy_position 203.974429 cc
2018-11-04T04:44:44.413Z,1541306684.413 [Reporter](INFO): platform_buoyancy_position 203.840114 cc
2018-11-04T04:44:44.818Z,1541306684.818 [Reporter](INFO): platform_buoyancy_position 205.988923 cc
2018-11-04T04:44:45.177Z,1541306685.177 [Reporter](INFO): platform_buoyancy_position 208.272031 cc
2018-11-04T04:44:45.178Z,1541306685.178 [Reporter](INFO): BuoyancyServo.component_avgCurrent 69.730006 mA
2018-11-04T04:44:45.576Z,1541306685.576 [Reporter](INFO): platform_buoyancy_position 212.435334 cc
2018-11-04T04:44:45.987Z,1541306685.987 [Reporter](INFO): platform_buoyancy_position 216.867222 cc
2018-11-04T04:44:46.385Z,1541306686.385 [Reporter](INFO): platform_buoyancy_position 223.179319 cc
2018-11-04T04:44:46.779Z,1541306686.779 [Reporter](INFO): platform_buoyancy_position 227.611235 cc
2018-11-04T04:44:47.183Z,1541306687.183 [Reporter](INFO): platform_buoyancy_position 233.923347 cc
2018-11-04T04:44:47.590Z,1541306687.590 [Reporter](INFO): platform_buoyancy_position 238.355220 cc
2018-11-04T04:44:47.590Z,1541306687.590 [Reporter](INFO): BuoyancyServo.component_avgCurrent 510.129988 mA
2018-11-04T04:44:47.979Z,1541306687.979 [Reporter](INFO): platform_buoyancy_position 244.801631 cc
2018-11-04T04:44:48.389Z,1541306688.389 [Reporter](INFO): platform_buoyancy_position 249.233533 cc
2018-11-04T04:44:48.783Z,1541306688.783 [Reporter](INFO): platform_buoyancy_position 255.411345 cc
2018-11-04T04:44:49.172Z,1541306689.172 [Reporter](INFO): platform_buoyancy_position 259.708933 cc
2018-11-04T04:44:49.583Z,1541306689.583 [Reporter](INFO): platform_buoyancy_position 265.886745 cc
2018-11-04T04:44:49.983Z,1541306689.983 [Reporter](INFO): platform_buoyancy_position 270.587247 cc
2018-11-04T04:44:49.983Z,1541306689.983 [Reporter](INFO): BuoyancyServo.component_avgCurrent 517.470002 mA
2018-11-04T04:44:50.364Z,1541306690.364 [Reporter](INFO): platform_buoyancy_position 275.422033 cc
2018-11-04T04:44:50.792Z,1541306690.792 [Reporter](INFO): platform_buoyancy_position 281.196932 cc
2018-11-04T04:44:51.194Z,1541306691.194 [Reporter](INFO): platform_buoyancy_position 285.628834 cc
2018-11-04T04:44:51.584Z,1541306691.584 [Reporter](INFO): platform_buoyancy_position 292.075216 cc
2018-11-04T04:44:51.987Z,1541306691.987 [Reporter](INFO): platform_buoyancy_position 296.372833 cc
2018-11-04T04:44:52.391Z,1541306692.391 [Reporter](INFO): platform_buoyancy_position 302.684930 cc
2018-11-04T04:44:52.391Z,1541306692.391 [Reporter](INFO): BuoyancyServo.component_avgCurrent 477.100015 mA
2018-11-04T04:44:52.768Z,1541306692.768 [Reporter](INFO): platform_buoyancy_position 307.116861 cc
2018-11-04T04:44:53.187Z,1541306693.187 [Reporter](INFO): platform_buoyancy_position 312.354561 cc
2018-11-04T04:44:53.568Z,1541306693.568 [Reporter](INFO): platform_buoyancy_position 317.726575 cc
2018-11-04T04:44:53.994Z,1541306693.994 [Reporter](INFO): platform_buoyancy_position 324.038643 cc
2018-11-04T04:44:54.366Z,1541306694.366 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-04T04:44:54.366Z,1541306694.366 [NAL9602] No Fault, FailCount= 5
2018-11-04T04:44:54.368Z,1541306694.368 [Reporter](INFO): platform_buoyancy_position 328.470516 cc
2018-11-04T04:44:54.698Z,1541306694.698 [NAL9602](INFO): Powering up NAL9602
2018-11-04T04:44:54.784Z,1541306694.784 [Reporter](INFO): platform_buoyancy_position 333.439646 cc
2018-11-04T04:44:54.784Z,1541306694.784 [Reporter](INFO): BuoyancyServo.component_avgCurrent 521.140039 mA
2018-11-04T04:44:55.172Z,1541306695.172 [Reporter](INFO): platform_buoyancy_position 339.348830 cc
2018-11-04T04:44:55.572Z,1541306695.572 [Reporter](INFO): platform_buoyancy_position 343.646418 cc
2018-11-04T04:44:55.984Z,1541306695.984 [Reporter](INFO): platform_buoyancy_position 350.092829 cc
2018-11-04T04:44:56.372Z,1541306696.372 [Reporter](INFO): platform_buoyancy_position 354.256161 cc
2018-11-04T04:44:56.845Z,1541306696.845 [Reporter](INFO): platform_buoyancy_position 360.702514 cc
2018-11-04T04:44:57.192Z,1541306697.192 [Reporter](INFO): platform_buoyancy_position 365.000131 cc
2018-11-04T04:44:57.192Z,1541306697.192 [Reporter](INFO): BuoyancyServo.component_avgCurrent 506.460011 mA
2018-11-04T04:44:57.651Z,1541306697.651 [Reporter](INFO): platform_buoyancy_position 371.446542 cc
2018-11-04T04:44:58.003Z,1541306698.003 [Reporter](INFO): platform_buoyancy_position 375.878415 cc
2018-11-04T04:44:58.391Z,1541306698.391 [Reporter](INFO): platform_buoyancy_position 380.041747 cc
2018-11-04T04:44:58.799Z,1541306698.799 [Reporter](INFO): platform_buoyancy_position 386.622443 cc
2018-11-04T04:44:59.189Z,1541306699.189 [Reporter](INFO): platform_buoyancy_position 390.920031 cc
2018-11-04T04:44:59.567Z,1541306699.567 [Reporter](INFO): platform_buoyancy_position 396.963558 cc
2018-11-04T04:44:59.568Z,1541306699.568 [Reporter](INFO): BuoyancyServo.component_avgCurrent 513.799965 mA
2018-11-04T04:44:59.973Z,1541306699.973 [Reporter](INFO): platform_buoyancy_position 401.664060 cc
2018-11-04T04:45:00.372Z,1541306700.372 [Reporter](INFO): platform_buoyancy_position 406.901730 cc
2018-11-04T04:45:00.768Z,1541306700.768 [Reporter](INFO): platform_buoyancy_position 412.542373 cc
2018-11-04T04:45:01.168Z,1541306701.168 [Reporter](INFO): platform_buoyancy_position 416.571362 cc
2018-11-04T04:45:01.570Z,1541306701.570 [Reporter](INFO): platform_buoyancy_position 423.017773 cc
2018-11-04T04:45:01.968Z,1541306701.968 [Reporter](INFO): platform_buoyancy_position 427.449646 cc
2018-11-04T04:45:01.968Z,1541306701.968 [Reporter](INFO): BuoyancyServo.component_avgCurrent 499.119997 mA
2018-11-04T04:45:02.372Z,1541306702.372 [Reporter](INFO): platform_buoyancy_position 433.761714 cc
2018-11-04T04:45:02.785Z,1541306702.785 [Reporter](INFO): platform_buoyancy_position 438.193674 cc
2018-11-04T04:45:03.175Z,1541306703.175 [Reporter](INFO): platform_buoyancy_position 444.371457 cc
2018-11-04T04:45:03.572Z,1541306703.572 [Reporter](INFO): platform_buoyancy_position 448.803359 cc
2018-11-04T04:45:03.985Z,1541306703.985 [Reporter](INFO): platform_buoyancy_position 454.175344 cc
2018-11-04T04:45:04.376Z,1541306704.376 [Reporter](INFO): platform_buoyancy_position 459.547358 cc
2018-11-04T04:45:04.377Z,1541306704.377 [Reporter](INFO): BuoyancyServo.component_avgCurrent 466.089994 mA
2018-11-04T04:45:04.776Z,1541306704.776 [Reporter](INFO): platform_buoyancy_position 464.113517 cc
2018-11-04T04:45:05.183Z,1541306705.183 [Reporter](INFO): platform_buoyancy_position 470.425672 cc
2018-11-04T04:45:05.581Z,1541306705.581 [Reporter](INFO): platform_buoyancy_position 474.588916 cc
2018-11-04T04:45:05.976Z,1541306705.976 [Reporter](INFO): platform_buoyancy_position 481.035328 cc
2018-11-04T04:45:06.375Z,1541306706.375 [Reporter](INFO): platform_buoyancy_position 485.467230 cc
2018-11-04T04:45:06.776Z,1541306706.776 [Reporter](INFO): platform_buoyancy_position 489.764847 cc
2018-11-04T04:45:06.785Z,1541306706.785 [Reporter](INFO): BuoyancyServo.component_avgCurrent 491.780013 mA
2018-11-04T04:45:07.189Z,1541306707.189 [Reporter](INFO): platform_buoyancy_position 496.076944 cc
2018-11-04T04:45:10.343Z,1541306710.343 [Reporter](INFO): platform_buoyancy_position 536.904146 cc
2018-11-04T04:45:10.467Z,1541306710.467 [Reporter](INFO): platform_buoyancy_position 539.187284 cc
2018-11-04T04:45:10.832Z,1541306710.832 [Reporter](INFO): platform_buoyancy_position 543.350528 cc
2018-11-04T04:45:11.299Z,1541306711.299 [Reporter](INFO): platform_buoyancy_position 551.945763 cc
2018-11-04T04:45:11.300Z,1541306711.300 [Reporter](INFO): BuoyancyServo.component_avgCurrent 510.129988 mA
2018-11-04T04:45:11.643Z,1541306711.643 [Reporter](INFO): platform_buoyancy_position 556.243351 cc
2018-11-04T04:45:12.044Z,1541306712.044 [Reporter](INFO): platform_buoyancy_position 560.540939 cc
2018-11-04T04:45:12.456Z,1541306712.456 [Reporter](INFO): platform_buoyancy_position 564.972812 cc
2018-11-04T04:45:12.841Z,1541306712.841 [Reporter](INFO): platform_buoyancy_position 569.270458 cc
2018-11-04T04:45:13.243Z,1541306713.243 [Reporter](INFO): platform_buoyancy_position 575.582555 cc
2018-11-04T04:45:13.652Z,1541306713.652 [Reporter](INFO): platform_buoyancy_position 580.014486 cc
2018-11-04T04:45:13.653Z,1541306713.653 [Reporter](INFO): BuoyancyServo.component_avgCurrent 510.129988 mA
2018-11-04T04:45:14.046Z,1541306714.046 [Reporter](INFO): platform_buoyancy_position 586.460868 cc
2018-11-04T04:45:14.444Z,1541306714.444 [Reporter](INFO): platform_buoyancy_position 590.758456 cc
2018-11-04T04:45:14.851Z,1541306714.851 [Reporter](INFO): platform_buoyancy_position 596.936268 cc
2018-11-04T04:45:15.240Z,1541306715.240 [Reporter](INFO): platform_buoyancy_position 601.502426 cc
2018-11-04T04:45:15.558Z,1541306715.558 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-04T04:45:15.558Z,1541306715.558 [NAL9602] Communications Fault, FailCount= 1
2018-11-04T04:45:15.558Z,1541306715.558 [NAL9602](ERROR): Communications Fault
2018-11-04T04:45:15.650Z,1541306715.650 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-04T04:45:15.652Z,1541306715.652 [Reporter](INFO): platform_buoyancy_position 607.948867 cc
2018-11-04T04:45:15.954Z,1541306715.954 [NAL9602](INFO): Powering down
2018-11-04T04:45:16.035Z,1541306716.035 [Reporter](INFO): platform_buoyancy_position 612.112170 cc
2018-11-04T04:45:16.036Z,1541306716.036 [Reporter](INFO): BuoyancyServo.component_avgCurrent 506.460011 mA
2018-11-04T04:45:16.445Z,1541306716.445 [Reporter](INFO): platform_buoyancy_position 616.812671 cc
2018-11-04T04:45:16.853Z,1541306716.853 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-04T04:45:16.853Z,1541306716.853 [NAL9602] No Fault, FailCount= 1
2018-11-04T04:45:16.854Z,1541306716.854 [Reporter](INFO): platform_buoyancy_position 622.990483 cc
2018-11-04T04:45:17.246Z,1541306717.246 [Reporter](INFO): platform_buoyancy_position 627.288071 cc
2018-11-04T04:45:17.611Z,1541306717.611 [Reporter](INFO): platform_buoyancy_position 631.988572 cc
2018-11-04T04:45:18.046Z,1541306718.046 [Reporter](INFO): platform_buoyancy_position 638.032041 cc
2018-11-04T04:45:18.444Z,1541306718.444 [Reporter](INFO): platform_buoyancy_position 644.478481 cc
2018-11-04T04:45:18.444Z,1541306718.444 [Reporter](INFO): BuoyancyServo.component_avgCurrent 543.160021 mA
2018-11-04T04:45:18.847Z,1541306718.847 [Reporter](INFO): platform_buoyancy_position 648.776069 cc
2018-11-04T04:45:19.247Z,1541306719.247 [Reporter](INFO): platform_buoyancy_position 654.953881 cc
2018-11-04T04:45:19.641Z,1541306719.641 [Reporter](INFO): platform_buoyancy_position 659.520098 cc
2018-11-04T04:45:20.050Z,1541306720.050 [Reporter](INFO): platform_buoyancy_position 665.294996 cc
2018-11-04T04:45:20.443Z,1541306720.443 [Reporter](INFO): platform_buoyancy_position 670.264009 cc
2018-11-04T04:45:20.843Z,1541306720.843 [Reporter](INFO): platform_buoyancy_position 676.441879 cc
2018-11-04T04:45:20.844Z,1541306720.844 [Reporter](INFO): BuoyancyServo.component_avgCurrent 510.129988 mA
2018-11-04T04:45:21.241Z,1541306721.241 [Reporter](INFO): platform_buoyancy_position 681.008038 cc
2018-11-04T04:45:21.643Z,1541306721.643 [Reporter](INFO): platform_buoyancy_position 685.439969 cc
2018-11-04T04:45:22.011Z,1541306722.011 [Reporter](INFO): platform_buoyancy_position 691.617723 cc
2018-11-04T04:45:22.446Z,1541306722.446 [Reporter](INFO): platform_buoyancy_position 696.049654 cc
2018-11-04T04:45:22.845Z,1541306722.845 [Reporter](INFO): platform_buoyancy_position 702.496094 cc
2018-11-04T04:45:23.283Z,1541306723.283 [Reporter](INFO): platform_buoyancy_position 706.793624 cc
2018-11-04T04:45:23.284Z,1541306723.284 [Reporter](INFO): BuoyancyServo.component_avgCurrent 495.450020 mA
2018-11-04T04:45:23.663Z,1541306723.663 [Reporter](INFO): platform_buoyancy_position 713.105779 cc
2018-11-04T04:45:24.058Z,1541306724.058 [Reporter](INFO): platform_buoyancy_position 717.403367 cc
2018-11-04T04:45:24.517Z,1541306724.517 [Reporter](INFO): platform_buoyancy_position 723.715464 cc
2018-11-04T04:45:24.855Z,1541306724.855 [Reporter](INFO): platform_buoyancy_position 728.281680 cc
2018-11-04T04:45:25.239Z,1541306725.239 [Reporter](INFO): platform_buoyancy_position 732.713554 cc
2018-11-04T04:45:25.639Z,1541306725.639 [Reporter](INFO): platform_buoyancy_position 738.891365 cc
2018-11-04T04:45:25.640Z,1541306725.640 [Reporter](INFO): BuoyancyServo.component_avgCurrent 477.100015 mA
2018-11-04T04:45:26.019Z,1541306726.019 [Reporter](INFO): platform_buoyancy_position 743.323239 cc
2018-11-04T04:45:26.450Z,1541306726.450 [Reporter](INFO): platform_buoyancy_position 749.635394 cc
2018-11-04T04:45:26.815Z,1541306726.815 [Reporter](INFO): platform_buoyancy_position 754.067267 cc
2018-11-04T04:45:27.239Z,1541306727.239 [Reporter](INFO): platform_buoyancy_position 760.245079 cc
2018-11-04T04:45:27.648Z,1541306727.648 [Reporter](INFO): platform_buoyancy_position 764.676952 cc
2018-11-04T04:45:28.015Z,1541306728.015 [Reporter](INFO): platform_buoyancy_position 769.108883 cc
2018-11-04T04:45:28.016Z,1541306728.016 [Reporter](INFO): BuoyancyServo.component_avgCurrent 491.780013 mA
2018-11-04T04:45:28.420Z,1541306728.420 [Reporter](INFO): platform_buoyancy_position 773.675041 cc
2018-11-04T04:45:28.823Z,1541306728.823 [Reporter](INFO): platform_buoyancy_position 779.718568 cc
2018-11-04T04:45:29.223Z,1541306729.223 [Reporter](INFO): platform_buoyancy_position 785.359181 cc
2018-11-04T04:45:29.627Z,1541306729.627 [Reporter](INFO): platform_buoyancy_position 790.462596 cc
2018-11-04T04:45:30.019Z,1541306730.019 [Reporter](INFO): platform_buoyancy_position 795.163098 cc
2018-11-04T04:45:30.429Z,1541306730.429 [Reporter](INFO): platform_buoyancy_position 801.340910 cc
2018-11-04T04:45:30.430Z,1541306730.430 [Reporter](INFO): BuoyancyServo.component_avgCurrent 539.489985 mA
2018-11-04T04:45:30.816Z,1541306730.816 [Reporter](INFO): platform_buoyancy_position 805.504154 cc
2018-11-04T04:45:31.233Z,1541306731.233 [Reporter](INFO): platform_buoyancy_position 811.950536 cc
2018-11-04T04:45:31.624Z,1541306731.624 [Reporter](INFO): platform_buoyancy_position 816.382468 cc
2018-11-04T04:45:32.020Z,1541306732.020 [Reporter](INFO): platform_buoyancy_position 822.694565 cc
2018-11-04T04:45:32.423Z,1541306732.423 [Reporter](INFO): platform_buoyancy_position 826.992211 cc
2018-11-04T04:45:32.831Z,1541306732.831 [Reporter](INFO): platform_buoyancy_position 831.424084 cc
2018-11-04T04:45:32.832Z,1541306732.832 [Reporter](INFO): BuoyancyServo.component_avgCurrent 524.810016 mA
2018-11-04T04:45:33.224Z,1541306733.224 [Reporter](INFO): platform_buoyancy_position 837.870466 cc
2018-11-04T04:45:33.631Z,1541306733.631 [Reporter](INFO): platform_buoyancy_position 842.168112 cc
2018-11-04T04:45:34.030Z,1541306734.030 [Reporter](INFO): platform_buoyancy_position 848.614494 cc
2018-11-04T04:45:34.424Z,1541306734.424 [Reporter](INFO): platform_buoyancy_position 852.777855 cc
2018-11-04T04:45:34.827Z,1541306734.827 [Reporter](INFO): platform_buoyancy_position 858.955551 cc
2018-11-04T04:45:35.220Z,1541306735.220 [Reporter](INFO): platform_buoyancy_position 863.656111 cc
2018-11-04T04:45:35.221Z,1541306735.221 [Reporter](INFO): BuoyancyServo.component_avgCurrent 546.829998 mA
2018-11-04T04:45:35.627Z,1541306735.627 [Reporter](INFO): platform_buoyancy_position 867.953757 cc
2018-11-04T04:45:36.034Z,1541306736.034 [Reporter](INFO): platform_buoyancy_position 874.400022 cc
2018-11-04T04:45:36.483Z,1541306736.483 [Reporter](INFO): platform_buoyancy_position 880.712178 cc
2018-11-04T04:45:36.839Z,1541306736.839 [Reporter](INFO): platform_buoyancy_position 885.144051 cc
2018-11-04T04:45:37.259Z,1541306737.259 [Reporter](INFO): platform_buoyancy_position 889.575924 cc
2018-11-04T04:45:37.718Z,1541306737.718 [Reporter](INFO): platform_buoyancy_position 895.753794 cc
2018-11-04T04:45:37.718Z,1541306737.718 [Reporter](INFO): BuoyancyServo.component_avgCurrent 433.059990 mA
2018-11-04T04:45:38.032Z,1541306738.032 [Reporter](INFO): platform_buoyancy_position 900.185667 cc
2018-11-04T04:45:38.436Z,1541306738.436 [Reporter](INFO): platform_buoyancy_position 906.632107 cc
2018-11-04T04:45:38.839Z,1541306738.839 [Reporter](INFO): platform_buoyancy_position 911.198265 cc
2018-11-04T04:45:39.236Z,1541306739.236 [Reporter](INFO): platform_buoyancy_position 917.241792 cc
2018-11-04T04:45:39.627Z,1541306739.627 [Reporter](INFO): platform_buoyancy_position 921.539438 cc
2018-11-04T04:45:40.031Z,1541306740.031 [Reporter](INFO): platform_buoyancy_position 926.508452 cc
2018-11-04T04:45:40.032Z,1541306740.032 [Reporter](INFO): BuoyancyServo.component_avgCurrent 473.430037 mA
2018-11-04T04:45:40.436Z,1541306740.436 [Reporter](INFO): platform_buoyancy_position 932.283350 cc
2018-11-04T04:45:40.829Z,1541306740.829 [Reporter](INFO): platform_buoyancy_position 936.849625 cc
2018-11-04T04:45:41.232Z,1541306741.232 [Reporter](INFO): platform_buoyancy_position 943.161722 cc
2018-11-04T04:45:41.659Z,1541306741.659 [Reporter](INFO): platform_buoyancy_position 947.324967 cc
2018-11-04T04:45:45.599Z,1541306745.599 [Reporter](INFO): BuoyancyServo.component_avgCurrent 0.000000 mA
2018-11-04T04:45:46.010Z,1541306746.010 [NAL9602](INFO): Powering up NAL9602
2018-11-04T04:45:52.430Z,1541306752.430 [CommandLine](IMPORTANT): got command quit
2018-11-04T04:45:53.439Z,1541306753.439 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-04T04:45:53.440Z,1541306753.440 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:53.593Z,1541306753.593 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-04T04:45:53.593Z,1541306753.593 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:53.594Z,1541306753.594 [CommandLine](INFO): Join timeout helper Thread ID is 5939
2018-11-04T04:45:53.594Z,1541306753.594 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-04T04:45:53.594Z,1541306753.594 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:53.595Z,1541306753.595 [NavChartDb](INFO): Join timeout helper Thread ID is 5940
2018-11-04T04:45:53.969Z,1541306753.969 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-04T04:45:53.969Z,1541306753.969 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:53.976Z,1541306753.976 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-04T04:45:53.977Z,1541306753.977 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:53.977Z,1541306753.977 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5941
2018-11-04T04:45:54.189Z,1541306754.189 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-04T04:45:54.189Z,1541306754.189 [WetLabsBB2FL](INFO): Powering down
2018-11-04T04:45:54.190Z,1541306754.190 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.197Z,1541306754.197 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-11-04T04:45:54.197Z,1541306754.197 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.197Z,1541306754.197 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5942
2018-11-04T04:45:54.289Z,1541306754.289 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-04T04:45:54.289Z,1541306754.289 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.297Z,1541306754.297 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-04T04:45:54.297Z,1541306754.297 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.297Z,1541306754.297 [Radio_Surface](INFO): Join timeout helper Thread ID is 5943
2018-11-04T04:45:54.397Z,1541306754.397 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-04T04:45:54.397Z,1541306754.397 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.419Z,1541306754.419 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-04T04:45:54.419Z,1541306754.419 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.420Z,1541306754.420 [logger](INFO): Join timeout helper Thread ID is 5944
2018-11-04T04:45:54.421Z,1541306754.421 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-04T04:45:54.422Z,1541306754.422 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.438Z,1541306754.438 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-04T04:45:54.438Z,1541306754.438 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.438Z,1541306754.438 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-04T04:45:54.438Z,1541306754.438 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.438Z,1541306754.438 [controlThread](INFO): Join timeout helper Thread ID is 5945
2018-11-04T04:45:54.448Z,1541306754.448 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-04T04:45:54.448Z,1541306754.448 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-04T04:45:54.449Z,1541306754.449 [NAL9602](INFO): Powering down
2018-11-04T04:45:54.451Z,1541306754.451 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-04T04:45:54.452Z,1541306754.452 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-04T04:45:54.453Z,1541306754.453 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-04T04:45:54.453Z,1541306754.453 [MissionManager](INFO): Uninitializing Mission Default
2018-11-04T04:45:54.453Z,1541306754.453 [Default] Stopped
2018-11-04T04:45:54.454Z,1541306754.454 [Default](DEBUG): Aggregate::uninitialize Default
2018-11-04T04:45:54.454Z,1541306754.454 [Default:B.GoToSurface] Stopped
2018-11-04T04:45:54.454Z,1541306754.454 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-04T04:45:54.454Z,1541306754.454 [Default:CheckIn] Stopped
2018-11-04T04:45:54.454Z,1541306754.454 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-04T04:45:54.454Z,1541306754.454 [Default:CheckIn:Read_GPS] Stopped
2018-11-04T04:45:54.457Z,1541306754.457 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-04T04:45:54.457Z,1541306754.457 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-04T04:45:54.458Z,1541306754.458 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-04T04:45:54.458Z,1541306754.458 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-04T04:45:54.458Z,1541306754.458 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-04T04:45:54.458Z,1541306754.458 [BuoyancyServo](INFO): Powering down
2018-11-04T04:45:54.473Z,1541306754.473 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-04T04:45:54.473Z,1541306754.473 [ElevatorServo](INFO): Powering down
2018-11-04T04:45:54.474Z,1541306754.474 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-04T04:45:54.474Z,1541306754.474 [MassServo](INFO): Powering down
2018-11-04T04:45:54.475Z,1541306754.475 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-04T04:45:54.475Z,1541306754.475 [RudderServo](INFO): Powering down
2018-11-04T04:45:54.476Z,1541306754.476 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-04T04:45:54.476Z,1541306754.476 [ThrusterServo](INFO): Powering down
2018-11-04T04:45:54.477Z,1541306754.477 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-04T04:45:54.477Z,1541306754.477 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-04T04:45:54.477Z,1541306754.477 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-04T04:45:54.478Z,1541306754.478 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.555Z,1541306754.555 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.593Z,1541306754.593 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.595Z,1541306754.595 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.640Z,1541306754.640 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-04T04:45:54.686Z,1541306754.686 [logger ThreadHandler](INFO): Thread cancelled.