2010-08-18T12:16:02.071Z,1282133762.071 [Supervisor](DEBUG): Initializing supervisor.
2010-08-18T12:16:02.076Z,1282133762.076 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2010-08-18T12:16:02.077Z,1282133762.077 [SyncHandler](INFO): Protected caller Thread ID is 9357
2010-08-18T12:16:02.077Z,1282133762.077 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2010-08-18T12:16:02.079Z,1282133762.079 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2010-08-18T12:16:02.079Z,1282133762.079 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9358
2010-08-18T12:16:02.083Z,1282133762.083 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2010-08-18T12:16:02.102Z,1282133762.102 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2010-08-18T12:16:02.103Z,1282133762.103 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2010-08-18T12:16:02.103Z,1282133762.103 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9359
2010-08-18T12:16:02.108Z,1282133762.108 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2010-08-18T12:16:02.109Z,1282133762.109 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2010-08-18T12:16:02.109Z,1282133762.109 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9360
2010-08-18T12:16:02.111Z,1282133762.111 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2010-08-18T12:16:02.112Z,1282133762.112 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2010-08-18T12:16:02.112Z,1282133762.112 [logger ThreadHandler](INFO): Protected caller Thread ID is 9361
2010-08-18T12:16:02.116Z,1282133762.116 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2010-08-18T12:16:02.117Z,1282133762.117 [Supervisor](INFO): Looking for Config files in directory: Config/
2010-08-18T12:16:02.118Z,1282133762.118 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2010-08-18T12:16:02.534Z,1282133762.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2010-08-18T12:16:02.535Z,1282133762.535 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2010-08-18T12:16:02.744Z,1282133762.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2010-08-18T12:16:02.745Z,1282133762.745 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2010-08-18T12:16:02.929Z,1282133762.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2010-08-18T12:16:02.930Z,1282133762.930 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2010-08-18T12:16:03.036Z,1282133763.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2010-08-18T12:16:03.037Z,1282133763.037 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2010-08-18T12:16:03.389Z,1282133763.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2010-08-18T12:16:03.390Z,1282133763.390 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2010-08-18T12:16:03.708Z,1282133763.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2010-08-18T12:16:03.709Z,1282133763.709 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2010-08-18T12:16:03.806Z,1282133763.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2010-08-18T12:16:03.806Z,1282133763.806 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2010-08-18T12:16:03.956Z,1282133763.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2010-08-18T12:16:03.956Z,1282133763.956 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2010-08-18T12:16:04.476Z,1282133764.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2010-08-18T12:16:04.476Z,1282133764.476 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2010-08-18T12:16:04.827Z,1282133764.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2010-08-18T12:16:04.828Z,1282133764.828 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2010-08-18T12:16:04.909Z,1282133764.909 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2010-08-18T12:16:05.620Z,1282133765.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2010-08-18T12:16:05.621Z,1282133765.621 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2010-08-18T12:16:06.053Z,1282133766.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2010-08-18T12:16:06.054Z,1282133766.054 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2010-08-18T12:16:07.015Z,1282133767.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2010-08-18T12:16:07.016Z,1282133767.016 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2010-08-18T12:16:07.095Z,1282133767.095 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2010-08-18T12:16:07.336Z,1282133767.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2010-08-18T12:16:07.338Z,1282133767.338 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2010-08-18T12:16:07.339Z,1282133767.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2010-08-18T12:16:07.504Z,1282133767.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2010-08-18T12:16:07.597Z,1282133767.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2010-08-18T12:16:07.708Z,1282133767.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2010-08-18T12:16:07.807Z,1282133767.807 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2010-08-18T12:16:07.891Z,1282133767.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2010-08-18T12:16:08.011Z,1282133768.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2010-08-18T12:16:08.126Z,1282133768.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2010-08-18T12:16:08.225Z,1282133768.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2010-08-18T12:16:08.364Z,1282133768.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2010-08-18T12:16:08.622Z,1282133768.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2010-08-18T12:16:08.622Z,1282133768.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2010-08-18T12:16:08.717Z,1282133768.717 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2010-08-18T12:16:08.718Z,1282133768.718 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2010-08-18T12:16:08.733Z,1282133768.733 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2010-08-18T12:16:09.064Z,1282133769.064 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2010-08-18T12:16:09.064Z,1282133769.064 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2010-08-18T12:16:09.145Z,1282133769.145 [DeadReckonUsingMultipleVelocitySources] Loaded
2010-08-18T12:16:09.146Z,1282133769.146 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2010-08-18T12:16:09.179Z,1282133769.179 [DeadReckonUsingSpeedCalculator] Loaded
2010-08-18T12:16:09.179Z,1282133769.179 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2010-08-18T12:16:09.192Z,1282133769.192 [NavChart] Loaded
2010-08-18T12:16:09.192Z,1282133769.192 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2010-08-18T12:16:09.198Z,1282133769.198 [UniversalFixResidualReporter] Loaded
2010-08-18T12:16:09.199Z,1282133769.199 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2010-08-18T12:16:09.199Z,1282133769.199 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2010-08-18T12:16:09.200Z,1282133769.200 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2010-08-18T12:16:09.210Z,1282133769.210 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2010-08-18T12:16:09.211Z,1282133769.211 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2010-08-18T12:16:09.404Z,1282133769.404 [CTD_Seabird] Loaded
2010-08-18T12:16:09.404Z,1282133769.404 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2010-08-18T12:16:09.406Z,1282133769.406 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406514E0
2010-08-18T12:16:09.406Z,1282133769.406 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9443
2010-08-18T12:16:09.425Z,1282133769.425 [PAR_Licor] Loaded
2010-08-18T12:16:09.426Z,1282133769.426 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2010-08-18T12:16:09.457Z,1282133769.457 [WetLabsSeaOWL_UV_A] Loaded
2010-08-18T12:16:09.458Z,1282133769.458 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2010-08-18T12:16:09.459Z,1282133769.459 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406814E0
2010-08-18T12:16:09.459Z,1282133769.459 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 9444
2010-08-18T12:16:09.460Z,1282133769.460 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2010-08-18T12:16:09.460Z,1282133769.460 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2010-08-18T12:16:09.516Z,1282133769.516 [VerticalControl](DEBUG): Construct VerticalControl.
2010-08-18T12:16:09.577Z,1282133769.577 [VerticalControl] Loaded
2010-08-18T12:16:09.577Z,1282133769.577 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2010-08-18T12:16:09.580Z,1282133769.580 [HorizontalControl](DEBUG): Construct HorizontalControl.
2010-08-18T12:16:09.618Z,1282133769.618 [HorizontalControl] Loaded
2010-08-18T12:16:09.619Z,1282133769.619 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2010-08-18T12:16:09.621Z,1282133769.621 [SpeedControl](DEBUG): Construct SpeedControl.
2010-08-18T12:16:09.624Z,1282133769.624 [SpeedControl] Loaded
2010-08-18T12:16:09.625Z,1282133769.625 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2010-08-18T12:16:09.627Z,1282133769.627 [LoopControl](DEBUG): Construct LoopControl.
2010-08-18T12:16:09.628Z,1282133769.628 [LoopControl] Loaded
2010-08-18T12:16:09.628Z,1282133769.628 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2010-08-18T12:16:09.628Z,1282133769.628 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2010-08-18T12:16:09.629Z,1282133769.629 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2010-08-18T12:16:09.672Z,1282133769.672 [DepthRateCalculator] Loaded
2010-08-18T12:16:09.672Z,1282133769.672 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2010-08-18T12:16:09.677Z,1282133769.677 [PitchRateCalculator] Loaded
2010-08-18T12:16:09.677Z,1282133769.677 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2010-08-18T12:16:09.687Z,1282133769.687 [SpeedCalculator] Loaded
2010-08-18T12:16:09.687Z,1282133769.687 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2010-08-18T12:16:09.700Z,1282133769.700 [TempGradientCalculator] Loaded
2010-08-18T12:16:09.700Z,1282133769.700 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2010-08-18T12:16:09.705Z,1282133769.705 [YawRateCalculator] Loaded
2010-08-18T12:16:09.705Z,1282133769.705 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2010-08-18T12:16:09.723Z,1282133769.723 [ElevatorOffsetCalculator] Loaded
2010-08-18T12:16:09.723Z,1282133769.723 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2010-08-18T12:16:09.723Z,1282133769.723 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2010-08-18T12:16:09.724Z,1282133769.724 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2010-08-18T12:16:09.818Z,1282133769.818 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2010-08-18T12:16:09.819Z,1282133769.819 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2010-08-18T12:16:09.878Z,1282133769.878 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2010-08-18T12:16:09.879Z,1282133769.879 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2010-08-18T12:16:10.012Z,1282133770.012 [BuoyancyServo] Loaded
2010-08-18T12:16:10.012Z,1282133770.012 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2010-08-18T12:16:10.032Z,1282133770.032 [ElevatorServo] Loaded
2010-08-18T12:16:10.032Z,1282133770.032 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2010-08-18T12:16:10.051Z,1282133770.051 [MassServo] Loaded
2010-08-18T12:16:10.051Z,1282133770.051 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2010-08-18T12:16:10.069Z,1282133770.069 [RudderServo] Loaded
2010-08-18T12:16:10.070Z,1282133770.070 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2010-08-18T12:16:10.084Z,1282133770.084 [ThrusterHE] Loaded
2010-08-18T12:16:10.085Z,1282133770.085 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2010-08-18T12:16:10.085Z,1282133770.085 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2010-08-18T12:16:10.086Z,1282133770.086 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2010-08-18T12:16:10.194Z,1282133770.194 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2010-08-18T12:16:10.194Z,1282133770.194 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2010-08-18T12:16:10.562Z,1282133770.562 [BackseatComponent] Loaded
2010-08-18T12:16:10.562Z,1282133770.562 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2010-08-18T12:16:10.563Z,1282133770.563 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0
2010-08-18T12:16:10.563Z,1282133770.563 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9445
2010-08-18T12:16:10.566Z,1282133770.566 [LcmUniversalReporter] Loaded
2010-08-18T12:16:10.566Z,1282133770.566 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2010-08-18T12:16:11.785Z,1282133771.785 [BPC1] Loaded
2010-08-18T12:16:11.785Z,1282133771.785 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2010-08-18T12:16:11.855Z,1282133771.855 [DataOverHttps] Loaded
2010-08-18T12:16:11.856Z,1282133771.856 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2010-08-18T12:16:11.857Z,1282133771.857 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A794E0
2010-08-18T12:16:11.857Z,1282133771.857 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9446
2010-08-18T12:16:11.877Z,1282133771.877 [Depth_Keller] Loaded
2010-08-18T12:16:11.877Z,1282133771.877 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2010-08-18T12:16:11.938Z,1282133771.938 [NAL9602] Loaded
2010-08-18T12:16:11.938Z,1282133771.938 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2010-08-18T12:16:11.968Z,1282133771.968 [Onboard] Loaded
2010-08-18T12:16:11.968Z,1282133771.968 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2010-08-18T12:16:11.969Z,1282133771.969 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0
2010-08-18T12:16:11.969Z,1282133771.969 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9447
2010-08-18T12:16:11.982Z,1282133771.982 [Power24vConverter] Loaded
2010-08-18T12:16:11.982Z,1282133771.982 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2010-08-18T12:16:11.995Z,1282133771.995 [Radio_Surface] Loaded
2010-08-18T12:16:11.996Z,1282133771.996 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2010-08-18T12:16:11.997Z,1282133771.997 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AD94E0
2010-08-18T12:16:11.997Z,1282133771.997 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9448
2010-08-18T12:16:11.998Z,1282133771.998 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2010-08-18T12:16:11.999Z,1282133771.999 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2010-08-18T12:16:12.146Z,1282133772.146 [SBIT](DEBUG): Construct Startup Built In Test.
2010-08-18T12:16:12.155Z,1282133772.155 [SBIT] Loaded
2010-08-18T12:16:12.155Z,1282133772.155 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2010-08-18T12:16:12.158Z,1282133772.158 [IBIT](DEBUG): Construct Initiated Built In Test.
2010-08-18T12:16:12.172Z,1282133772.172 [IBIT] Loaded
2010-08-18T12:16:12.172Z,1282133772.172 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2010-08-18T12:16:12.178Z,1282133772.178 [CBIT](DEBUG): Construct Continuous Built In Test.
2010-08-18T12:16:12.279Z,1282133772.279 [CBIT] Loaded
2010-08-18T12:16:12.279Z,1282133772.279 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2010-08-18T12:16:12.280Z,1282133772.280 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2010-08-18T12:16:12.280Z,1282133772.280 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2010-08-18T12:16:12.309Z,1282133772.309 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2010-08-18T12:16:12.315Z,1282133772.315 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2010-08-18T12:16:12.318Z,1282133772.318 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2010-08-18T12:16:12.329Z,1282133772.329 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2010-08-18T12:16:12.330Z,1282133772.330 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0
2010-08-18T12:16:12.331Z,1282133772.331 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9449
2010-08-18T12:16:12.335Z,1282133772.335 [Supervisor](INFO): Main Thread ID is 6436
2010-08-18T12:16:12.335Z,1282133772.335 [Supervisor](DEBUG): Running supervisor.
2010-08-18T12:16:12.336Z,1282133772.336 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9450
2010-08-18T12:16:12.336Z,1282133772.336 [CommandExec](INFO): Initializing the command executive.
2010-08-18T12:16:12.338Z,1282133772.338 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9451
2010-08-18T12:16:12.340Z,1282133772.340 [controlThread ThreadHandler](INFO): Handler Thread ID is 9452
2010-08-18T12:16:12.341Z,1282133772.341 [controlThread](DEBUG): Initializing ControlThread
2010-08-18T12:16:12.342Z,1282133772.342 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2010-08-18T12:16:12.343Z,1282133772.343 [NavChart](DEBUG): Initialize NavChart Navigation.
2010-08-18T12:16:12.343Z,1282133772.343 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2010-08-18T12:16:12.346Z,1282133772.346 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2010-08-18T12:16:12.348Z,1282133772.348 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2010-08-18T12:16:12.348Z,1282133772.348 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2010-08-18T12:16:12.349Z,1282133772.349 [LoopControl](DEBUG): Initialize LoopControlComponent.
2010-08-18T12:16:12.349Z,1282133772.349 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2010-08-18T12:16:12.350Z,1282133772.350 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2010-08-18T12:16:12.350Z,1282133772.350 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2010-08-18T12:16:12.350Z,1282133772.350 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2010-08-18T12:16:12.351Z,1282133772.351 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2010-08-18T12:16:12.351Z,1282133772.351 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2010-08-18T12:16:12.355Z,1282133772.355 [SBIT](INFO): Initialize SBIT Component.
2010-08-18T12:16:12.355Z,1282133772.355 [SBIT](IMPORTANT): git: 2021-10-11a
2010-08-18T12:16:12.355Z,1282133772.355 [SBIT](INFO): git hash: 36f3a97de7a122c995f034a375e3cefac6406048
2010-08-18T12:16:12.356Z,1282133772.356 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2010-08-18T12:16:12.357Z,1282133772.357 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2010-08-18T12:16:12.358Z,1282133772.358 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2010-08-18T12:16:12.359Z,1282133772.359 [IBIT](INFO): Initialize IBIT Component.
2010-08-18T12:16:12.360Z,1282133772.360 [CBIT](DEBUG): Initialize CBIT Component.
2010-08-18T12:16:12.360Z,1282133772.360 [logger ThreadHandler](INFO): Handler Thread ID is 9453
2010-08-18T12:16:12.374Z,1282133772.374 [CBIT](DEBUG): Initialized mux pins.
2010-08-18T12:16:12.374Z,1282133772.374 [CBIT](DEBUG): Initializing the watchdog timer.
2010-08-18T12:16:12.378Z,1282133772.378 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9454
2010-08-18T12:16:12.379Z,1282133772.379 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2010-08-18T12:16:12.383Z,1282133772.383 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 9456
2010-08-18T12:16:12.394Z,1282133772.394 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9457
2010-08-18T12:16:12.398Z,1282133772.398 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2010-08-18T12:16:12.398Z,1282133772.398 [CBIT](DEBUG): Initializing heartbeat.
2010-08-18T12:16:12.406Z,1282133772.406 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9458
2010-08-18T12:16:12.407Z,1282133772.407 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2010-08-18T12:16:12.418Z,1282133772.418 [Onboard ThreadHandler](INFO): Handler Thread ID is 9459
2010-08-18T12:16:12.436Z,1282133772.436 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9460
2010-08-18T12:16:12.454Z,1282133772.454 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9461
2010-08-18T12:16:12.457Z,1282133772.457 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2010-08-18T12:16:12.458Z,1282133772.458 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2010-08-18T12:16:12.458Z,1282133772.458 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2010-08-18T12:16:12.458Z,1282133772.458 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2010-08-18T12:16:12.458Z,1282133772.458 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2010-08-18T12:16:12.458Z,1282133772.458 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2010-08-18T12:16:12.458Z,1282133772.458 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2010-08-18T12:16:12.459Z,1282133772.459 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2010-08-18T12:16:12.459Z,1282133772.459 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2010-08-18T12:16:12.459Z,1282133772.459 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2010-08-18T12:16:12.459Z,1282133772.459 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2010-08-18T12:16:12.459Z,1282133772.459 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2010-08-18T12:16:12.460Z,1282133772.460 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2010-08-18T12:16:12.460Z,1282133772.460 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2010-08-18T12:16:12.460Z,1282133772.460 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2010-08-18T12:16:12.460Z,1282133772.460 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2010-08-18T12:16:12.469Z,1282133772.469 [CBIT](DEBUG): Deactivating GF circuits.
2010-08-18T12:16:12.470Z,1282133772.470 [CBIT](DEBUG): Deactivating emergency mode.
2010-08-18T12:16:12.505Z,1282133772.505 [CBIT](DEBUG): Backplane powered.
2010-08-18T12:16:12.507Z,1282133772.507 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2010-08-18T12:16:12.520Z,1282133772.520 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2010-08-18T12:16:12.533Z,1282133772.533 [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>


2010-08-18T12:16:12.553Z,1282133772.553 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2010-08-18T12:16:12.634Z,1282133772.634 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2010-08-18T12:16:12.635Z,1282133772.635 [Default:A.Wait](DEBUG): Construct Wait.
2010-08-18T12:16:12.637Z,1282133772.637 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2010-08-18T12:16:12.677Z,1282133772.677 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2010-08-18T12:16:12.680Z,1282133772.680 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2010-08-18T12:16:12.713Z,1282133772.713 [Default:E.Execute](DEBUG): Construct Execute.
2010-08-18T12:16:12.731Z,1282133772.731 [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>


2010-08-18T12:16:12.735Z,1282133772.735 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2010-08-18T12:16:12.813Z,1282133772.813 [Power24vConverter](INFO): Powering up.
2010-08-18T12:16:12.842Z,1282133772.842 [Radio_Surface](INFO): Powering up
2010-08-18T12:16:12.895Z,1282133772.895 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2010-08-18T12:16:12.902Z,1282133772.902 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2010-08-18T12:16:12.903Z,1282133772.903 [ElevatorServo](DEBUG): Initializing EZServoServo.
2010-08-18T12:16:12.910Z,1282133772.910 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2010-08-18T12:16:12.911Z,1282133772.911 [MassServo](DEBUG): Initializing EZServoServo.
2010-08-18T12:16:12.926Z,1282133772.926 [MassServo](DEBUG): Initializing MassServo.
2010-08-18T12:16:12.927Z,1282133772.927 [RudderServo](DEBUG): Initializing EZServoServo.
2010-08-18T12:16:12.934Z,1282133772.934 [RudderServo](DEBUG): Initializing RudderServo.
2010-08-18T12:16:12.935Z,1282133772.935 [ThrusterHE](INFO): Start
2010-08-18T12:16:12.935Z,1282133772.935 [ThrusterHE](DEBUG): Initializing EZServoServo.
2010-08-18T12:16:12.942Z,1282133772.942 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2010-08-18T12:16:13.200Z,1282133773.200 [ThrusterHE](INFO): Starting
2010-08-18T12:16:13.882Z,1282133773.882 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2010-08-18T12:16:13.882Z,1282133773.882 [RudderServo](FAULT): Rudder failed to initialize
2010-08-18T12:16:13.882Z,1282133773.882 [RudderServo] Communications Fault, FailCount= 1
2010-08-18T12:16:13.882Z,1282133773.882 [RudderServo](ERROR): Communications Fault
2010-08-18T12:16:13.882Z,1282133773.882 [ThrusterHE](INFO): Starting
2010-08-18T12:16:13.886Z,1282133773.886 [CBIT](ERROR): Communications Fault in component: RudderServo
2010-08-18T12:16:14.087Z,1282133774.087 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2010-08-18T12:16:14.087Z,1282133774.087 [RudderServo](INFO): Powering down
2010-08-18T12:16:14.088Z,1282133774.088 [ThrusterHE](INFO): Starting
2010-08-18T12:16:14.377Z,1282133774.377 [ThrusterHE](INFO): Starting
2010-08-18T12:16:14.804Z,1282133774.804 [RudderServo](DEBUG): Initializing EZServoServo.
2010-08-18T12:16:14.922Z,1282133774.922 [RudderServo](DEBUG): Initializing RudderServo.
2010-08-18T12:16:14.923Z,1282133774.923 [ThrusterHE](INFO): Starting
2010-08-18T12:16:14.927Z,1282133774.927 [CBIT](INFO): Clearing failed state for component RudderServo
2010-08-18T12:16:14.927Z,1282133774.927 [RudderServo] No Fault, FailCount= 1
2010-08-18T12:16:15.187Z,1282133775.187 [ThrusterHE](INFO): Starting
2010-08-18T12:16:15.187Z,1282133775.187 [ThrusterHE](INFO): Normal Promp. Good Init
2010-08-18T12:16:15.734Z,1282133775.734 [ThrusterHE](INFO): Starting
2010-08-18T12:16:15.995Z,1282133775.995 [ThrusterHE](INFO): Starting
2010-08-18T12:16:16.503Z,1282133776.503 [ThrusterHE](INFO): Starting
2010-08-18T12:16:16.926Z,1282133776.926 [ThrusterHE](INFO): Starting
2010-08-18T12:16:17.230Z,1282133777.230 [ThrusterHE](INFO): Starting
2010-08-18T12:16:17.611Z,1282133777.611 [ThrusterHE](INFO): Starting
2010-08-18T12:16:18.074Z,1282133778.074 [ThrusterHE](INFO): Starting
2010-08-18T12:16:18.670Z,1282133778.670 [ThrusterHE](INFO): Runnable
2010-08-18T12:16:18.670Z,1282133778.670 [ThrusterHE](INFO): Unexpected data on serial port:ZSpeed
2010-08-18T12:16:18.671Z,1282133778.671 [ThrusterHE](ERROR): Zero Speed Commanded.
2010-08-18T12:16:18.671Z,1282133778.671 [ThrusterHE](INFO): Fault detected
2010-08-18T12:16:18.671Z,1282133778.671 [ThrusterHE](INFO): Send speed command forward:0000
2010-08-18T12:16:18.884Z,1282133778.884 [ThrusterHE](INFO): Pause
2010-08-18T12:16:25.446Z,1282133785.446 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2010-08-18T12:16:25.446Z,1282133785.446 [CTD_Seabird] Communications Fault, FailCount= 1
2010-08-18T12:16:25.446Z,1282133785.446 [CTD_Seabird](ERROR): Communications Fault
2010-08-18T12:16:25.716Z,1282133785.716 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2010-08-18T12:16:25.851Z,1282133785.851 [CTD_Seabird](INFO): Powering down
2010-08-18T12:16:26.915Z,1282133786.915 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2010-08-18T12:16:26.915Z,1282133786.915 [CTD_Seabird] No Fault, FailCount= 1
2010-08-18T12:16:27.194Z,1282133787.194 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2010-08-18T12:16:32.993Z,1282133792.993 [SBIT](IMPORTANT): Beginning Startup BIT
2010-08-18T12:16:32.997Z,1282133792.997 [CBIT](IMPORTANT): Beginning ground fault scan
2010-08-18T12:16:40.250Z,1282133800.250 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2010-08-18T12:16:40.250Z,1282133800.250 [CTD_Seabird] Communications Fault, FailCount= 2
2010-08-18T12:16:40.250Z,1282133800.250 [CTD_Seabird](ERROR): Communications Fault
2010-08-18T12:16:40.272Z,1282133800.272 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2010-08-18T12:16:40.654Z,1282133800.654 [CTD_Seabird](INFO): Powering down
2010-08-18T12:16:41.922Z,1282133801.922 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2010-08-18T12:16:41.923Z,1282133801.923 [CTD_Seabird] No Fault, FailCount= 2
2010-08-18T12:16:41.994Z,1282133801.994 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2010-08-18T12:16:42.239Z,1282133802.239 [NAL9602](INFO): Powering up NAL9602
2010-08-18T12:16:43.957Z,1282133803.957 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.028451
CHAN A1 (24V): 0.001419
CHAN A2 (12V): -0.005307
CHAN A3 (5V): -0.002505
CHAN B0 (3.3V): -0.001281
CHAN B1 (3.15aV): -0.001565
CHAN B2 (3.15bV): -0.001400
CHAN B3 (GND): -0.000940
OPEN: 0.004930
Full Scale: +/- 1 mA
2010-08-18T12:16:53.155Z,1282133813.155 [NAL9602](INFO): NAL9602 initialized
2010-08-18T12:16:55.054Z,1282133815.054 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2010-08-18T12:16:55.054Z,1282133815.054 [CTD_Seabird] Communications Fault, FailCount= 3
2010-08-18T12:16:55.054Z,1282133815.054 [CTD_Seabird](ERROR): Communications Fault
2010-08-18T12:16:55.243Z,1282133815.243 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2010-08-18T12:16:55.243Z,1282133815.243 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2010-08-18T12:16:55.458Z,1282133815.458 [CTD_Seabird](INFO): Powering down
2010-08-18T12:17:18.471Z,1282133838.471 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2010-08-18T12:17:18.474Z,1282133838.474 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2010-08-18T12:17:18.532Z,1282133838.532 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2010-08-18T12:17:18.678Z,1282133838.678 [ThrusterHE](INFO): Resume
2010-08-18T12:17:19.059Z,1282133839.059 [ThrusterHE](INFO): Resuming
2010-08-18T12:17:19.462Z,1282133839.462 [ThrusterHE](INFO): Resuming
2010-08-18T12:17:19.863Z,1282133839.863 [ThrusterHE](INFO): Resuming
2010-08-18T12:17:20.263Z,1282133840.263 [ThrusterHE](INFO): Resuming
2010-08-18T12:17:20.671Z,1282133840.671 [ThrusterHE](INFO): Resuming
2010-08-18T12:17:21.066Z,1282133841.066 [ThrusterHE](INFO): Resuming
2010-08-18T12:17:21.067Z,1282133841.067 [ThrusterHE](INFO): Normal Promp. Good Init
2010-08-18T12:17:21.474Z,1282133841.474 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:21.475Z,1282133841.475 [ThrusterHE](INFO): Unexpected data on serial port:WU
eed
2010-08-18T12:17:21.477Z,1282133841.477 [ThrusterHE](INFO): Send speed command forward:00FF
2010-08-18T12:17:21.878Z,1282133841.878 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:21.879Z,1282133841.879 [ThrusterHE](INFO): Unexpected data on serial port:FW,00FF
2010-08-18T12:17:22.287Z,1282133842.287 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:22.712Z,1282133842.712 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:22.712Z,1282133842.712 [ThrusterHE](INFO): Unexpected data on serial port:

>

>F
2010-08-18T12:17:23.211Z,1282133843.211 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:23.244Z,1282133843.244 [ThrusterHE](INFO): Speed Good:12.904092
2010-08-18T12:17:23.488Z,1282133843.488 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:23.489Z,1282133843.489 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

001F

2010-08-18T12:17:23.506Z,1282133843.506 [ThrusterHE](INFO): Speed Good:19.564268
2010-08-18T12:17:23.885Z,1282133843.885 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:23.885Z,1282133843.885 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

002F

2010-08-18T12:17:23.910Z,1282133843.910 [ThrusterHE](INFO): Speed Good:22.478096
2010-08-18T12:17:24.279Z,1282133844.279 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:24.279Z,1282133844.279 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0036

2010-08-18T12:17:24.298Z,1282133844.298 [ThrusterHE](INFO): Speed Good:24.143139
2010-08-18T12:17:24.684Z,1282133844.684 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:24.684Z,1282133844.684 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

003A

2010-08-18T12:17:24.710Z,1282133844.710 [ThrusterHE](INFO): Speed Good:25.391922
2010-08-18T12:17:25.093Z,1282133845.093 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:25.093Z,1282133845.093 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

003D

2010-08-18T12:17:25.118Z,1282133845.118 [ThrusterHE](INFO): Speed Good:26.224445
2010-08-18T12:17:25.489Z,1282133845.489 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:25.489Z,1282133845.489 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

003F

2010-08-18T12:17:25.506Z,1282133845.506 [ThrusterHE](INFO): Speed Good:27.056967
2010-08-18T12:17:25.892Z,1282133845.892 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:25.892Z,1282133845.892 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0041

2010-08-18T12:17:25.922Z,1282133845.922 [ThrusterHE](INFO): Speed Good:27.889488
2010-08-18T12:17:26.359Z,1282133846.359 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:26.360Z,1282133846.360 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0043

2010-08-18T12:17:26.386Z,1282133846.386 [ThrusterHE](INFO): Speed Good:28.305750
2010-08-18T12:17:26.737Z,1282133846.737 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:26.738Z,1282133846.738 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0044

2010-08-18T12:17:26.762Z,1282133846.762 [ThrusterHE](INFO): Speed Good:28.722012
2010-08-18T12:17:26.763Z,1282133846.763 [SBIT](IMPORTANT): SBIT PASSED
2010-08-18T12:17:26.763Z,1282133846.763 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2010-08-18T12:17:26.764Z,1282133846.764 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2010-08-18T12:17:26.764Z,1282133846.764 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2010-08-18T12:17:26.764Z,1282133846.764 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere;

2010-08-18T12:17:26.764Z,1282133846.764 [SBIT](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2010-08-18T12:17:26.764Z,1282133846.764 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;

2010-08-18T12:17:26.765Z,1282133846.765 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2010-08-18T12:17:26.765Z,1282133846.765 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;

2010-08-18T12:17:26.765Z,1282133846.765 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;

2010-08-18T12:17:26.765Z,1282133846.765 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2010-08-18T12:17:26.765Z,1282133846.765 [SBIT](IMPORTANT): PAR_Licor.adcCal=8.76539e-06 microampere_per_count;

2010-08-18T12:17:26.765Z,1282133846.765 [SBIT](IMPORTANT): PAR_Licor.darkCount=4 count;

2010-08-18T12:17:26.794Z,1282133846.794 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2010-08-18T12:17:26.794Z,1282133846.794 [SBIT](IMPORTANT): PAR_Licor.multiplier=269.34 micromole_per_second_per_square_meter_per_microampere;

2010-08-18T12:17:26.795Z,1282133846.795 [SBIT](IMPORTANT): PAR_Licor.parCal=1.221 none;

2010-08-18T12:17:26.795Z,1282133846.795 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=235 cubic_centimeter;

2010-08-18T12:17:26.795Z,1282133846.795 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter;

2010-08-18T12:17:26.795Z,1282133846.795 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-27 millimeter;

2010-08-18T12:17:26.795Z,1282133846.795 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=37 millimeter;

2010-08-18T12:17:27.103Z,1282133847.103 [MissionManager](IMPORTANT): Started mission Startup
2010-08-18T12:17:27.103Z,1282133847.103 [Startup] Running Loop=1
2010-08-18T12:17:27.103Z,1282133847.103 [Startup](DEBUG): Aggregate::initialize Startup
2010-08-18T12:17:27.103Z,1282133847.103 [Startup:A.GoToSurface] Running Loop=1
2010-08-18T12:17:27.103Z,1282133847.103 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2010-08-18T12:17:27.104Z,1282133847.104 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2010-08-18T12:17:27.104Z,1282133847.104 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2010-08-18T12:17:27.105Z,1282133847.105 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2010-08-18T12:17:27.105Z,1282133847.105 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2010-08-18T12:17:27.106Z,1282133847.106 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2010-08-18T12:17:27.107Z,1282133847.107 [Startup:StartupSatComms] Running Loop=1
2010-08-18T12:17:27.107Z,1282133847.107 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2010-08-18T12:17:27.107Z,1282133847.107 [Startup:StartupSatComms:A] Running Loop=1
2010-08-18T12:17:27.119Z,1282133847.119 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:27.119Z,1282133847.119 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0045

2010-08-18T12:17:27.138Z,1282133847.138 [ThrusterHE](INFO): Speed Good:29.138271
2010-08-18T12:17:27.535Z,1282133847.535 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2010-08-18T12:17:27.542Z,1282133847.542 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:27.542Z,1282133847.542 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0046

2010-08-18T12:17:27.566Z,1282133847.566 [ThrusterHE](INFO): Speed Good:29.554533
2010-08-18T12:17:27.934Z,1282133847.934 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:27.934Z,1282133847.934 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0047

2010-08-18T12:17:27.958Z,1282133847.958 [ThrusterHE](INFO): Speed Good:29.970795
2010-08-18T12:17:28.371Z,1282133848.371 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:28.371Z,1282133848.371 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0048

2010-08-18T12:17:28.399Z,1282133848.399 [ThrusterHE](INFO): Speed Good:29.970795
2010-08-18T12:17:28.765Z,1282133848.765 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:28.765Z,1282133848.765 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0048

2010-08-18T12:17:28.790Z,1282133848.790 [ThrusterHE](INFO): Speed Good:30.387054
2010-08-18T12:17:29.137Z,1282133849.137 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:29.138Z,1282133849.138 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0049

2010-08-18T12:17:29.162Z,1282133849.162 [ThrusterHE](INFO): Speed Good:30.387054
2010-08-18T12:17:29.544Z,1282133849.544 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:29.545Z,1282133849.545 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0049

2010-08-18T12:17:29.570Z,1282133849.570 [ThrusterHE](INFO): Speed Good:30.387054
2010-08-18T12:17:29.934Z,1282133849.934 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:29.934Z,1282133849.934 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0049

2010-08-18T12:17:29.956Z,1282133849.956 [ThrusterHE](INFO): Speed Good:30.387054
2010-08-18T12:17:30.339Z,1282133850.339 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:30.339Z,1282133850.339 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

0049

2010-08-18T12:17:30.366Z,1282133850.366 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:30.753Z,1282133850.753 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:30.754Z,1282133850.754 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:30.778Z,1282133850.778 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:31.149Z,1282133851.149 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:31.150Z,1282133851.150 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:31.178Z,1282133851.178 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:31.548Z,1282133851.548 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:31.548Z,1282133851.548 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:31.574Z,1282133851.574 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:31.961Z,1282133851.961 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:31.962Z,1282133851.962 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:31.986Z,1282133851.986 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:32.358Z,1282133852.358 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:32.358Z,1282133852.358 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:32.395Z,1282133852.395 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:32.763Z,1282133852.763 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:32.763Z,1282133852.763 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:32.790Z,1282133852.790 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:33.186Z,1282133853.186 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:33.187Z,1282133853.187 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:33.222Z,1282133853.222 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:33.589Z,1282133853.589 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:33.590Z,1282133853.590 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:33.614Z,1282133853.614 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:33.982Z,1282133853.982 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:33.982Z,1282133853.982 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:34.010Z,1282133854.010 [ThrusterHE](INFO): Speed Good:30.803316
2010-08-18T12:17:34.398Z,1282133854.398 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:34.399Z,1282133854.399 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004A

2010-08-18T12:17:34.430Z,1282133854.430 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:34.792Z,1282133854.792 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:34.792Z,1282133854.792 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:34.818Z,1282133854.818 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:35.184Z,1282133855.184 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:35.185Z,1282133855.185 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:35.206Z,1282133855.206 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:35.593Z,1282133855.593 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:35.593Z,1282133855.593 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:35.618Z,1282133855.618 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:36.001Z,1282133856.001 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:36.001Z,1282133856.001 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:36.026Z,1282133856.026 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:36.396Z,1282133856.397 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:36.397Z,1282133856.397 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:36.426Z,1282133856.426 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:36.802Z,1282133856.802 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:36.803Z,1282133856.803 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:36.830Z,1282133856.830 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:37.208Z,1282133857.208 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:37.209Z,1282133857.209 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:37.234Z,1282133857.234 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:37.619Z,1282133857.619 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:37.620Z,1282133857.620 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:37.646Z,1282133857.646 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:38.014Z,1282133858.014 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:38.015Z,1282133858.015 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:38.035Z,1282133858.035 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:38.449Z,1282133858.449 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:38.449Z,1282133858.449 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:38.474Z,1282133858.474 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:38.834Z,1282133858.834 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:38.835Z,1282133858.835 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:38.850Z,1282133858.850 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:39.259Z,1282133859.259 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:39.259Z,1282133859.259 [ThrusterHE](INFO): Unexpected data on serial port:
>,78

004B

2010-08-18T12:17:39.283Z,1282133859.283 [CommandExec](IMPORTANT): got command maintain clear
2010-08-18T12:17:39.294Z,1282133859.294 [ThrusterHE](INFO): Speed Good:31.219578
2010-08-18T12:17:39.339Z,1282133859.339 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2010-08-18T12:17:39.699Z,1282133859.699 [ThrusterHE](INFO): Runnable
2010-08-18T12:17:39.699Z,1282133859.699 [ThrusterHE](INFO): Unexpected data on serial port:>W,78

004B

2010-08-18T12:17:40.062Z,1282133860.062 [ThrusterHE](INFO): Pause
2010-08-18T12:18:12.342Z,1282133892.342 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2010-08-18T12:18:12.342Z,1282133892.342 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2010-08-18T12:18:12.343Z,1282133892.343 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2010-08-18T12:18:12.344Z,1282133892.344 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2010-08-18T12:18:12.344Z,1282133892.344 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2010-08-18T12:18:12.344Z,1282133892.344 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2010-08-18T12:18:12.385Z,1282133892.385 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2010-08-18T12:18:12.385Z,1282133892.385 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2010-08-18T12:18:12.776Z,1282133892.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2010-08-18T12:18:12.776Z,1282133892.776 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2010-08-18T12:18:12.776Z,1282133892.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2010-08-18T12:18:12.777Z,1282133892.777 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2010-08-18T12:18:13.144Z,1282133893.144 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2010-08-18T12:18:27.292Z,1282133907.292 [Startup:StartupSatComms:A](INFO): Timed out from 2010-08-18T12:17:27.1Z
2010-08-18T12:18:27.292Z,1282133907.292 [Startup:StartupSatComms:A] Stopped
2010-08-18T12:18:27.292Z,1282133907.292 [Startup:StartupSatComms:B] Running Loop=1
2010-08-18T12:18:27.698Z,1282133907.698 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2010-08-18T12:18:42.521Z,1282133922.521 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005144
2010-08-18T12:18:49.617Z,1282133929.617 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20100818T121203/Courier0004.lzma
2010-08-18T12:18:50.619Z,1282133930.619 [DataOverHttps](INFO): Moved sent file to Logs/20100818T121203/Courier0004.lzma.bak
2010-08-18T12:18:50.620Z,1282133930.620 [DataOverHttps](INFO): SBD MOMSN=16172103
2010-08-18T12:19:05.913Z,1282133945.913 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20100818T121602/Courier0000.lzma
2010-08-18T12:19:06.915Z,1282133946.915 [DataOverHttps](INFO): Moved sent file to Logs/20100818T121602/Courier0000.lzma.bak
2010-08-18T12:19:06.916Z,1282133946.916 [DataOverHttps](INFO): SBD MOMSN=16172105
2010-08-18T12:19:25.234Z,1282133965.234 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2010-08-18T12:19:25.234Z,1282133965.234 [CTD_Seabird] No Fault, FailCount= 3
2010-08-18T12:19:25.614Z,1282133965.614 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2010-08-18T12:19:26.346Z,1282133966.346 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20100818T121203/Express0005.lzma
2010-08-18T12:19:27.347Z,1282133967.347 [DataOverHttps](INFO): Moved sent file to Logs/20100818T121203/Express0005.lzma.bak
2010-08-18T12:19:27.348Z,1282133967.348 [DataOverHttps](INFO): SBD MOMSN=16172108
2010-08-18T12:19:27.563Z,1282133967.563 [Startup:StartupSatComms:B](INFO): Timed out from 2010-08-18T12:18:27.3Z
2010-08-18T12:19:27.564Z,1282133967.564 [Startup:StartupSatComms:B] Stopped
2010-08-18T12:19:27.564Z,1282133967.564 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2010-08-18T12:19:27.564Z,1282133967.564 [Startup:StartupSatComms] Stopped
2010-08-18T12:19:27.564Z,1282133967.564 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2010-08-18T12:19:27.565Z,1282133967.565 [Startup](INFO): Completed Startup
2010-08-18T12:19:27.565Z,1282133967.565 [MissionManager](INFO): Startup is completed.
2010-08-18T12:19:27.565Z,1282133967.565 [MissionManager](INFO): Uninitializing Mission Startup
2010-08-18T12:19:27.566Z,1282133967.566 [Startup] Stopped
2010-08-18T12:19:27.566Z,1282133967.566 [Startup](DEBUG): Aggregate::uninitialize Startup
2010-08-18T12:19:27.566Z,1282133967.566 [Startup:A.GoToSurface] Stopped
2010-08-18T12:19:27.566Z,1282133967.566 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2010-08-18T12:19:27.979Z,1282133967.979 [MissionManager](IMPORTANT): Started mission Default
2010-08-18T12:19:27.979Z,1282133967.979 [Default] Running Loop=1
2010-08-18T12:19:27.980Z,1282133967.980 [Default](DEBUG): Aggregate::initialize Default
2010-08-18T12:19:27.980Z,1282133967.980 [Default:B.GoToSurface] Running Loop=1
2010-08-18T12:19:27.980Z,1282133967.980 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2010-08-18T12:19:27.980Z,1282133967.980 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2010-08-18T12:19:27.980Z,1282133967.980 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2010-08-18T12:19:27.981Z,1282133967.981 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2010-08-18T12:19:27.981Z,1282133967.981 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2010-08-18T12:19:28.006Z,1282133968.006 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2010-08-18T12:19:28.006Z,1282133968.006 [Default:A.Wait] Running Loop=1
2010-08-18T12:19:28.006Z,1282133968.006 [Default:A.Wait](DEBUG): Initialize Wait Component.
2010-08-18T12:19:36.943Z,1282133976.943 [CommandExec](IMPORTANT): got command restart application
2010-08-18T12:19:37.946Z,1282133977.946 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2010-08-18T12:19:37.946Z,1282133977.946 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:37.946Z,1282133977.946 [CommandExec](INFO): Uninitializing the command executive.
2010-08-18T12:19:37.946Z,1282133977.946 [CommandExec](INFO): Uninitializing the command scheduler.
2010-08-18T12:19:37.946Z,1282133977.946 [CommandExec ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:37.947Z,1282133977.947 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2010-08-18T12:19:37.947Z,1282133977.947 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2010-08-18T12:19:37.947Z,1282133977.947 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:37.948Z,1282133977.948 [NavChartDb](INFO): Join timeout helper Thread ID is 9492
2010-08-18T12:19:38.006Z,1282133978.006 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:38.006Z,1282133978.006 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:38.026Z,1282133978.026 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2010-08-18T12:19:38.026Z,1282133978.026 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:38.026Z,1282133978.026 [Radio_Surface](INFO): Join timeout helper Thread ID is 9493
2010-08-18T12:19:38.178Z,1282133978.178 [Radio_Surface](INFO): Powering down
2010-08-18T12:19:38.179Z,1282133978.179 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:38.179Z,1282133978.179 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:38.194Z,1282133978.194 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2010-08-18T12:19:38.194Z,1282133978.194 [Onboard ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:38.194Z,1282133978.194 [Onboard](INFO): Join timeout helper Thread ID is 9494
2010-08-18T12:19:38.682Z,1282133978.682 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2010-08-18T12:19:38.682Z,1282133978.682 [CTD_Seabird] Communications Fault, FailCount= 1
2010-08-18T12:19:38.682Z,1282133978.682 [CTD_Seabird](ERROR): Communications Fault
2010-08-18T12:19:38.842Z,1282133978.842 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2010-08-18T12:19:38.909Z,1282133978.909 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2010-08-18T12:19:39.086Z,1282133979.086 [CTD_Seabird](INFO): Powering down
2010-08-18T12:19:40.125Z,1282133980.125 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2010-08-18T12:19:40.125Z,1282133980.125 [CTD_Seabird] No Fault, FailCount= 1
2010-08-18T12:19:40.426Z,1282133980.426 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2010-08-18T12:19:41.312Z,1282133981.312 [Default:A.Wait](INFO): Done Waiting.
2010-08-18T12:19:41.312Z,1282133981.312 [Default:A.Wait] Stopped
2010-08-18T12:19:41.312Z,1282133981.312 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2010-08-18T12:19:41.713Z,1282133981.713 [Default:CheckIn] Running Loop=1
2010-08-18T12:19:41.713Z,1282133981.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2010-08-18T12:19:41.713Z,1282133981.713 [Default:CheckIn:Read_GPS] Running Loop=1
2010-08-18T12:19:42.116Z,1282133982.116 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2010-08-18T12:19:42.602Z,1282133982.602 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:42.602Z,1282133982.602 [Onboard ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:42.621Z,1282133982.621 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2010-08-18T12:19:42.622Z,1282133982.622 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:42.622Z,1282133982.622 [DataOverHttps](INFO): Join timeout helper Thread ID is 9496
2010-08-18T12:19:42.858Z,1282133982.858 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:42.858Z,1282133982.858 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:42.878Z,1282133982.878 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2010-08-18T12:19:42.878Z,1282133982.878 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:42.878Z,1282133982.878 [BackseatComponent](INFO): Join timeout helper Thread ID is 9497
2010-08-18T12:19:43.034Z,1282133983.034 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:43.034Z,1282133983.034 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.038Z,1282133983.038 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2010-08-18T12:19:43.039Z,1282133983.039 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.039Z,1282133983.039 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9498
2010-08-18T12:19:43.130Z,1282133983.130 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:43.130Z,1282133983.130 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.146Z,1282133983.146 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2010-08-18T12:19:43.146Z,1282133983.146 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.147Z,1282133983.147 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9499
2010-08-18T12:19:43.438Z,1282133983.438 [CTD_Seabird](INFO): Powering down
2010-08-18T12:19:43.566Z,1282133983.566 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:43.566Z,1282133983.566 [CTD_Seabird](INFO): Powering down
2010-08-18T12:19:43.578Z,1282133983.578 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.579Z,1282133983.579 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2010-08-18T12:19:43.579Z,1282133983.579 [logger ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.579Z,1282133983.579 [logger](INFO): Join timeout helper Thread ID is 9500
2010-08-18T12:19:43.590Z,1282133983.590 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:43.590Z,1282133983.590 [logger ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.598Z,1282133983.598 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2010-08-18T12:19:43.598Z,1282133983.598 [CommandLine ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.599Z,1282133983.599 [CommandLine](INFO): Join timeout helper Thread ID is 9501
2010-08-18T12:19:43.626Z,1282133983.626 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:43.626Z,1282133983.626 [CommandLine ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.638Z,1282133983.638 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2010-08-18T12:19:43.638Z,1282133983.638 [CommandExec ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.639Z,1282133983.639 [CommandExec](INFO): Join timeout helper Thread ID is 9502
2010-08-18T12:19:43.640Z,1282133983.640 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2010-08-18T12:19:43.640Z,1282133983.640 [controlThread ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.640Z,1282133983.640 [controlThread](INFO): Join timeout helper Thread ID is 9503
2010-08-18T12:19:43.710Z,1282133983.710 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2010-08-18T12:19:43.710Z,1282133983.710 [controlThread](DEBUG): Uninitializing ControlThread
2010-08-18T12:19:43.711Z,1282133983.711 [NAL9602](INFO): Powering down
2010-08-18T12:19:43.713Z,1282133983.713 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2010-08-18T12:19:43.714Z,1282133983.714 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2010-08-18T12:19:43.714Z,1282133983.714 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2010-08-18T12:19:43.715Z,1282133983.715 [MissionManager](INFO): Uninitializing Mission Default
2010-08-18T12:19:43.715Z,1282133983.715 [Default] Stopped
2010-08-18T12:19:43.715Z,1282133983.715 [Default](DEBUG): Aggregate::uninitialize Default
2010-08-18T12:19:43.715Z,1282133983.715 [Default:B.GoToSurface] Stopped
2010-08-18T12:19:43.715Z,1282133983.715 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2010-08-18T12:19:43.715Z,1282133983.715 [Default:CheckIn] Stopped
2010-08-18T12:19:43.715Z,1282133983.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2010-08-18T12:19:43.716Z,1282133983.716 [Default:CheckIn:Read_GPS] Stopped
2010-08-18T12:19:43.719Z,1282133983.719 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2010-08-18T12:19:43.719Z,1282133983.719 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2010-08-18T12:19:43.719Z,1282133983.719 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2010-08-18T12:19:43.719Z,1282133983.719 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2010-08-18T12:19:43.720Z,1282133983.720 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2010-08-18T12:19:43.720Z,1282133983.720 [BuoyancyServo](INFO): Powering down
2010-08-18T12:19:43.734Z,1282133983.734 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2010-08-18T12:19:43.734Z,1282133983.734 [ElevatorServo](INFO): Powering down
2010-08-18T12:19:43.735Z,1282133983.735 [MassServo](DEBUG): Uninitialize Mass Servo.
2010-08-18T12:19:43.735Z,1282133983.735 [MassServo](INFO): Powering down
2010-08-18T12:19:43.736Z,1282133983.736 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2010-08-18T12:19:43.736Z,1282133983.736 [RudderServo](INFO): Powering down
2010-08-18T12:19:43.737Z,1282133983.737 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2010-08-18T12:19:43.737Z,1282133983.737 [ThrusterHE](INFO): Powering down
2010-08-18T12:19:43.738Z,1282133983.738 [SBIT](DEBUG): Uninitialize SBIT Component.
2010-08-18T12:19:43.738Z,1282133983.738 [IBIT](DEBUG): Uninitialize IBIT Component.
2010-08-18T12:19:43.739Z,1282133983.739 [CBIT](DEBUG): Uninitialize CBIT Component.
2010-08-18T12:19:43.739Z,1282133983.739 [CBIT](DEBUG): Powering off loads.
2010-08-18T12:19:43.749Z,1282133983.749 [CBIT](DEBUG): Disabling WDT.
2010-08-18T12:19:43.761Z,1282133983.761 [CBIT](DEBUG): Opening all GF detection circuits.
2010-08-18T12:19:43.762Z,1282133983.762 [controlThread ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.799Z,1282133983.799 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.803Z,1282133983.803 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.849Z,1282133983.849 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.851Z,1282133983.851 [Onboard ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.857Z,1282133983.857 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.902Z,1282133983.902 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:43.940Z,1282133983.940 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2010-08-18T12:19:44.004Z,1282133984.004 [logger ThreadHandler](INFO): Thread cancelled.