2010-08-18T11:14:05.126Z,1282130045.126 [Supervisor](DEBUG): Initializing supervisor. 2010-08-18T11:14:05.132Z,1282130045.132 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2010-08-18T11:14:05.132Z,1282130045.132 [SyncHandler](INFO): Protected caller Thread ID is 7767 2010-08-18T11:14:05.133Z,1282130045.133 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2010-08-18T11:14:05.134Z,1282130045.134 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2010-08-18T11:14:05.134Z,1282130045.134 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7768 2010-08-18T11:14:05.139Z,1282130045.139 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2010-08-18T11:14:05.158Z,1282130045.158 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2010-08-18T11:14:05.159Z,1282130045.159 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2010-08-18T11:14:05.159Z,1282130045.159 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7769 2010-08-18T11:14:05.163Z,1282130045.163 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2010-08-18T11:14:05.164Z,1282130045.164 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2010-08-18T11:14:05.165Z,1282130045.165 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7770 2010-08-18T11:14:05.167Z,1282130045.167 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2010-08-18T11:14:05.168Z,1282130045.168 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2010-08-18T11:14:05.168Z,1282130045.168 [logger ThreadHandler](INFO): Protected caller Thread ID is 7771 2010-08-18T11:14:05.172Z,1282130045.172 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2010-08-18T11:14:05.173Z,1282130045.173 [Supervisor](INFO): Looking for Config files in directory: Config/ 2010-08-18T11:14:05.174Z,1282130045.174 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2010-08-18T11:14:05.635Z,1282130045.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2010-08-18T11:14:05.635Z,1282130045.635 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2010-08-18T11:14:05.855Z,1282130045.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2010-08-18T11:14:05.856Z,1282130045.856 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2010-08-18T11:14:06.047Z,1282130046.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2010-08-18T11:14:06.048Z,1282130046.048 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2010-08-18T11:14:06.156Z,1282130046.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2010-08-18T11:14:06.157Z,1282130046.157 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2010-08-18T11:14:06.531Z,1282130046.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2010-08-18T11:14:06.532Z,1282130046.532 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2010-08-18T11:14:06.870Z,1282130046.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2010-08-18T11:14:06.870Z,1282130046.870 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2010-08-18T11:14:06.968Z,1282130046.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2010-08-18T11:14:06.968Z,1282130046.968 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2010-08-18T11:14:07.124Z,1282130047.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2010-08-18T11:14:07.124Z,1282130047.124 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2010-08-18T11:14:07.676Z,1282130047.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2010-08-18T11:14:07.677Z,1282130047.677 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2010-08-18T11:14:08.049Z,1282130048.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2010-08-18T11:14:08.050Z,1282130048.050 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2010-08-18T11:14:08.131Z,1282130048.131 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2010-08-18T11:14:08.339Z,1282130048.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2010-08-18T11:14:08.340Z,1282130048.340 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2010-08-18T11:14:08.469Z,1282130048.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2010-08-18T11:14:08.470Z,1282130048.470 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2010-08-18T11:14:09.159Z,1282130049.159 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2010-08-18T11:14:09.160Z,1282130049.160 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2010-08-18T11:14:09.239Z,1282130049.239 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2010-08-18T11:14:09.492Z,1282130049.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2010-08-18T11:14:09.494Z,1282130049.494 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2010-08-18T11:14:09.495Z,1282130049.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2010-08-18T11:14:09.667Z,1282130049.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2010-08-18T11:14:09.760Z,1282130049.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2010-08-18T11:14:09.874Z,1282130049.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2010-08-18T11:14:09.975Z,1282130049.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2010-08-18T11:14:10.058Z,1282130050.058 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2010-08-18T11:14:10.181Z,1282130050.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2010-08-18T11:14:10.298Z,1282130050.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2010-08-18T11:14:10.398Z,1282130050.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2010-08-18T11:14:10.542Z,1282130050.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2010-08-18T11:14:10.879Z,1282130050.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2010-08-18T11:14:10.880Z,1282130050.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2010-08-18T11:14:11.123Z,1282130051.123 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2010-08-18T11:14:11.124Z,1282130051.124 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2010-08-18T11:14:11.141Z,1282130051.141 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2010-08-18T11:14:11.714Z,1282130051.714 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2010-08-18T11:14:11.715Z,1282130051.715 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2010-08-18T11:14:11.798Z,1282130051.798 [DeadReckonUsingMultipleVelocitySources] Loaded 2010-08-18T11:14:11.799Z,1282130051.799 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2010-08-18T11:14:11.833Z,1282130051.833 [DeadReckonUsingSpeedCalculator] Loaded 2010-08-18T11:14:11.833Z,1282130051.833 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2010-08-18T11:14:11.846Z,1282130051.846 [NavChart] Loaded 2010-08-18T11:14:11.846Z,1282130051.846 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2010-08-18T11:14:11.852Z,1282130051.852 [UniversalFixResidualReporter] Loaded 2010-08-18T11:14:11.853Z,1282130051.853 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2010-08-18T11:14:11.853Z,1282130051.853 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2010-08-18T11:14:11.854Z,1282130051.854 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2010-08-18T11:14:11.865Z,1282130051.865 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2010-08-18T11:14:11.865Z,1282130051.865 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2010-08-18T11:14:12.069Z,1282130052.069 [CTD_Seabird] Loaded 2010-08-18T11:14:12.069Z,1282130052.069 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2010-08-18T11:14:12.070Z,1282130052.070 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406514E0 2010-08-18T11:14:12.070Z,1282130052.070 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7853 2010-08-18T11:14:12.091Z,1282130052.091 [PAR_Licor] Loaded 2010-08-18T11:14:12.091Z,1282130052.091 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2010-08-18T11:14:12.123Z,1282130052.123 [WetLabsSeaOWL_UV_A] Loaded 2010-08-18T11:14:12.124Z,1282130052.124 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2010-08-18T11:14:12.125Z,1282130052.125 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406814E0 2010-08-18T11:14:12.125Z,1282130052.125 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7854 2010-08-18T11:14:12.126Z,1282130052.126 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2010-08-18T11:14:12.127Z,1282130052.127 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2010-08-18T11:14:12.185Z,1282130052.185 [VerticalControl](DEBUG): Construct VerticalControl. 2010-08-18T11:14:12.247Z,1282130052.247 [VerticalControl] Loaded 2010-08-18T11:14:12.247Z,1282130052.247 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2010-08-18T11:14:12.250Z,1282130052.250 [HorizontalControl](DEBUG): Construct HorizontalControl. 2010-08-18T11:14:12.289Z,1282130052.289 [HorizontalControl] Loaded 2010-08-18T11:14:12.289Z,1282130052.289 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2010-08-18T11:14:12.292Z,1282130052.292 [SpeedControl](DEBUG): Construct SpeedControl. 2010-08-18T11:14:12.295Z,1282130052.295 [SpeedControl] Loaded 2010-08-18T11:14:12.295Z,1282130052.295 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2010-08-18T11:14:12.298Z,1282130052.298 [LoopControl](DEBUG): Construct LoopControl. 2010-08-18T11:14:12.299Z,1282130052.299 [LoopControl] Loaded 2010-08-18T11:14:12.299Z,1282130052.299 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2010-08-18T11:14:12.299Z,1282130052.299 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2010-08-18T11:14:12.300Z,1282130052.300 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2010-08-18T11:14:12.345Z,1282130052.345 [DepthRateCalculator] Loaded 2010-08-18T11:14:12.345Z,1282130052.345 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2010-08-18T11:14:12.350Z,1282130052.350 [PitchRateCalculator] Loaded 2010-08-18T11:14:12.350Z,1282130052.350 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2010-08-18T11:14:12.360Z,1282130052.360 [SpeedCalculator] Loaded 2010-08-18T11:14:12.360Z,1282130052.360 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2010-08-18T11:14:12.374Z,1282130052.374 [TempGradientCalculator] Loaded 2010-08-18T11:14:12.374Z,1282130052.374 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2010-08-18T11:14:12.378Z,1282130052.378 [YawRateCalculator] Loaded 2010-08-18T11:14:12.379Z,1282130052.379 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2010-08-18T11:14:12.396Z,1282130052.396 [ElevatorOffsetCalculator] Loaded 2010-08-18T11:14:12.396Z,1282130052.396 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2010-08-18T11:14:12.397Z,1282130052.397 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2010-08-18T11:14:12.397Z,1282130052.397 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2010-08-18T11:14:12.498Z,1282130052.498 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2010-08-18T11:14:12.499Z,1282130052.499 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2010-08-18T11:14:12.562Z,1282130052.562 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2010-08-18T11:14:12.562Z,1282130052.562 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2010-08-18T11:14:12.702Z,1282130052.702 [BuoyancyServo] Loaded 2010-08-18T11:14:12.702Z,1282130052.702 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2010-08-18T11:14:12.722Z,1282130052.722 [ElevatorServo] Loaded 2010-08-18T11:14:12.722Z,1282130052.722 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2010-08-18T11:14:12.742Z,1282130052.742 [MassServo] Loaded 2010-08-18T11:14:12.742Z,1282130052.742 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2010-08-18T11:14:12.760Z,1282130052.760 [RudderServo] Loaded 2010-08-18T11:14:12.761Z,1282130052.761 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2010-08-18T11:14:12.776Z,1282130052.776 [ThrusterHE] Loaded 2010-08-18T11:14:12.776Z,1282130052.776 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2010-08-18T11:14:12.777Z,1282130052.777 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2010-08-18T11:14:12.777Z,1282130052.777 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2010-08-18T11:14:12.891Z,1282130052.891 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2010-08-18T11:14:12.892Z,1282130052.892 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2010-08-18T11:14:13.275Z,1282130053.275 [BackseatComponent] Loaded 2010-08-18T11:14:13.275Z,1282130053.275 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2010-08-18T11:14:13.276Z,1282130053.276 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0 2010-08-18T11:14:13.276Z,1282130053.276 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7855 2010-08-18T11:14:13.279Z,1282130053.279 [LcmUniversalReporter] Loaded 2010-08-18T11:14:13.279Z,1282130053.279 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2010-08-18T11:14:14.129Z,1282130054.129 [BPC1] Loaded 2010-08-18T11:14:14.129Z,1282130054.129 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2010-08-18T11:14:14.209Z,1282130054.209 [DataOverHttps] Loaded 2010-08-18T11:14:14.209Z,1282130054.209 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2010-08-18T11:14:14.210Z,1282130054.210 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A794E0 2010-08-18T11:14:14.210Z,1282130054.210 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7856 2010-08-18T11:14:14.231Z,1282130054.231 [Depth_Keller] Loaded 2010-08-18T11:14:14.231Z,1282130054.231 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2010-08-18T11:14:14.294Z,1282130054.294 [NAL9602] Loaded 2010-08-18T11:14:14.294Z,1282130054.294 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2010-08-18T11:14:14.324Z,1282130054.324 [Onboard] Loaded 2010-08-18T11:14:14.325Z,1282130054.325 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2010-08-18T11:14:14.326Z,1282130054.326 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2010-08-18T11:14:14.326Z,1282130054.326 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7857 2010-08-18T11:14:14.340Z,1282130054.340 [Power24vConverter] Loaded 2010-08-18T11:14:14.340Z,1282130054.340 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2010-08-18T11:14:14.354Z,1282130054.354 [Radio_Surface] Loaded 2010-08-18T11:14:14.354Z,1282130054.354 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2010-08-18T11:14:14.355Z,1282130054.355 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AD94E0 2010-08-18T11:14:14.355Z,1282130054.355 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7858 2010-08-18T11:14:14.357Z,1282130054.357 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2010-08-18T11:14:14.357Z,1282130054.357 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2010-08-18T11:14:14.513Z,1282130054.513 [SBIT](DEBUG): Construct Startup Built In Test. 2010-08-18T11:14:14.523Z,1282130054.523 [SBIT] Loaded 2010-08-18T11:14:14.523Z,1282130054.523 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2010-08-18T11:14:14.526Z,1282130054.526 [IBIT](DEBUG): Construct Initiated Built In Test. 2010-08-18T11:14:14.540Z,1282130054.540 [IBIT] Loaded 2010-08-18T11:14:14.540Z,1282130054.540 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2010-08-18T11:14:14.546Z,1282130054.546 [CBIT](DEBUG): Construct Continuous Built In Test. 2010-08-18T11:14:14.649Z,1282130054.649 [CBIT] Loaded 2010-08-18T11:14:14.649Z,1282130054.649 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2010-08-18T11:14:14.650Z,1282130054.650 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2010-08-18T11:14:14.650Z,1282130054.650 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2010-08-18T11:14:14.680Z,1282130054.680 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2010-08-18T11:14:14.687Z,1282130054.687 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2010-08-18T11:14:14.689Z,1282130054.689 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2010-08-18T11:14:14.700Z,1282130054.700 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2010-08-18T11:14:14.701Z,1282130054.701 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0 2010-08-18T11:14:14.702Z,1282130054.702 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7859 2010-08-18T11:14:14.706Z,1282130054.706 [Supervisor](INFO): Main Thread ID is 6436 2010-08-18T11:14:14.706Z,1282130054.706 [Supervisor](DEBUG): Running supervisor. 2010-08-18T11:14:14.707Z,1282130054.707 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7860 2010-08-18T11:14:14.708Z,1282130054.708 [CommandExec](INFO): Initializing the command executive. 2010-08-18T11:14:14.709Z,1282130054.709 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7861 2010-08-18T11:14:14.711Z,1282130054.711 [controlThread ThreadHandler](INFO): Handler Thread ID is 7862 2010-08-18T11:14:14.712Z,1282130054.712 [controlThread](DEBUG): Initializing ControlThread 2010-08-18T11:14:14.713Z,1282130054.713 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2010-08-18T11:14:14.714Z,1282130054.714 [NavChart](DEBUG): Initialize NavChart Navigation. 2010-08-18T11:14:14.714Z,1282130054.714 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2010-08-18T11:14:14.718Z,1282130054.718 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2010-08-18T11:14:14.720Z,1282130054.720 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2010-08-18T11:14:14.721Z,1282130054.721 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2010-08-18T11:14:14.721Z,1282130054.721 [LoopControl](DEBUG): Initialize LoopControlComponent. 2010-08-18T11:14:14.721Z,1282130054.721 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2010-08-18T11:14:14.722Z,1282130054.722 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2010-08-18T11:14:14.722Z,1282130054.722 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2010-08-18T11:14:14.722Z,1282130054.722 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2010-08-18T11:14:14.723Z,1282130054.723 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2010-08-18T11:14:14.723Z,1282130054.723 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2010-08-18T11:14:14.727Z,1282130054.727 [SBIT](INFO): Initialize SBIT Component. 2010-08-18T11:14:14.727Z,1282130054.727 [SBIT](IMPORTANT): git: 2021-10-11a 2010-08-18T11:14:14.727Z,1282130054.727 [SBIT](INFO): git hash: 36f3a97de7a122c995f034a375e3cefac6406048 2010-08-18T11:14:14.728Z,1282130054.728 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2010-08-18T11:14:14.729Z,1282130054.729 [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-18T11:14:14.730Z,1282130054.730 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2010-08-18T11:14:14.731Z,1282130054.731 [IBIT](INFO): Initialize IBIT Component. 2010-08-18T11:14:14.732Z,1282130054.732 [CBIT](DEBUG): Initialize CBIT Component. 2010-08-18T11:14:14.733Z,1282130054.733 [logger ThreadHandler](INFO): Handler Thread ID is 7863 2010-08-18T11:14:14.746Z,1282130054.746 [CBIT](DEBUG): Initialized mux pins. 2010-08-18T11:14:14.746Z,1282130054.746 [CBIT](DEBUG): Initializing the watchdog timer. 2010-08-18T11:14:14.750Z,1282130054.750 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7864 2010-08-18T11:14:14.751Z,1282130054.751 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:14:14.755Z,1282130054.755 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7866 2010-08-18T11:14:14.766Z,1282130054.766 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7867 2010-08-18T11:14:14.770Z,1282130054.770 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2010-08-18T11:14:14.770Z,1282130054.770 [CBIT](DEBUG): Initializing heartbeat. 2010-08-18T11:14:14.778Z,1282130054.778 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7868 2010-08-18T11:14:14.779Z,1282130054.779 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2010-08-18T11:14:14.790Z,1282130054.790 [Onboard ThreadHandler](INFO): Handler Thread ID is 7869 2010-08-18T11:14:14.808Z,1282130054.808 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7870 2010-08-18T11:14:14.826Z,1282130054.826 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7871 2010-08-18T11:14:14.829Z,1282130054.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2010-08-18T11:14:14.830Z,1282130054.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2010-08-18T11:14:14.830Z,1282130054.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2010-08-18T11:14:14.830Z,1282130054.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2010-08-18T11:14:14.830Z,1282130054.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2010-08-18T11:14:14.831Z,1282130054.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2010-08-18T11:14:14.831Z,1282130054.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2010-08-18T11:14:14.831Z,1282130054.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2010-08-18T11:14:14.831Z,1282130054.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2010-08-18T11:14:14.831Z,1282130054.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2010-08-18T11:14:14.832Z,1282130054.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2010-08-18T11:14:14.832Z,1282130054.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2010-08-18T11:14:14.832Z,1282130054.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2010-08-18T11:14:14.832Z,1282130054.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2010-08-18T11:14:14.832Z,1282130054.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2010-08-18T11:14:14.832Z,1282130054.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2010-08-18T11:14:14.841Z,1282130054.841 [CBIT](DEBUG): Deactivating GF circuits. 2010-08-18T11:14:14.842Z,1282130054.842 [CBIT](DEBUG): Deactivating emergency mode. 2010-08-18T11:14:14.877Z,1282130054.877 [CBIT](DEBUG): Backplane powered. 2010-08-18T11:14:14.879Z,1282130054.879 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2010-08-18T11:14:14.892Z,1282130054.892 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2010-08-18T11:14:14.905Z,1282130054.905 [MissionManager](DEBUG): 2010-08-18T11:14:14.934Z,1282130054.934 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2010-08-18T11:14:15.011Z,1282130055.011 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2010-08-18T11:14:15.013Z,1282130055.013 [Default:A.Wait](DEBUG): Construct Wait. 2010-08-18T11:14:15.015Z,1282130055.015 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2010-08-18T11:14:15.071Z,1282130055.071 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2010-08-18T11:14:15.073Z,1282130055.073 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2010-08-18T11:14:15.083Z,1282130055.083 [Default:E.Execute](DEBUG): Construct Execute. 2010-08-18T11:14:15.103Z,1282130055.103 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2010-08-18T11:14:15.115Z,1282130055.115 [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-18T11:14:15.188Z,1282130055.188 [Power24vConverter](INFO): Powering up. 2010-08-18T11:14:15.214Z,1282130055.214 [Radio_Surface](INFO): Powering up 2010-08-18T11:14:15.230Z,1282130055.230 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2010-08-18T11:14:15.238Z,1282130055.238 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2010-08-18T11:14:15.239Z,1282130055.239 [ElevatorServo](DEBUG): Initializing EZServoServo. 2010-08-18T11:14:15.246Z,1282130055.246 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2010-08-18T11:14:15.247Z,1282130055.247 [MassServo](DEBUG): Initializing EZServoServo. 2010-08-18T11:14:15.254Z,1282130055.254 [MassServo](DEBUG): Initializing MassServo. 2010-08-18T11:14:15.255Z,1282130055.255 [RudderServo](DEBUG): Initializing EZServoServo. 2010-08-18T11:14:15.262Z,1282130055.262 [RudderServo](DEBUG): Initializing RudderServo. 2010-08-18T11:14:15.263Z,1282130055.263 [ThrusterHE](INFO): Start 2010-08-18T11:14:15.263Z,1282130055.263 [ThrusterHE](DEBUG): Initializing EZServoServo. 2010-08-18T11:14:15.270Z,1282130055.270 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2010-08-18T11:14:15.587Z,1282130055.587 [ThrusterHE](INFO): Starting 2010-08-18T11:14:16.003Z,1282130056.003 [ThrusterHE](INFO): Starting 2010-08-18T11:14:16.487Z,1282130056.487 [ThrusterHE](INFO): Starting 2010-08-18T11:14:16.759Z,1282130056.759 [ThrusterHE](INFO): Starting 2010-08-18T11:14:17.187Z,1282130057.187 [ThrusterHE](INFO): Starting 2010-08-18T11:14:17.606Z,1282130057.606 [ThrusterHE](INFO): Starting 2010-08-18T11:14:18.082Z,1282130058.082 [ThrusterHE](INFO): Runnable 2010-08-18T11:14:18.083Z,1282130058.083 [ThrusterHE](INFO): Unexpected data on serial port: tarting 2010-08-18T11:14:18.084Z,1282130058.084 [ThrusterHE](INFO): Send speed command forward:0000 2010-08-18T11:14:18.439Z,1282130058.439 [ThrusterHE](INFO): Pause 2010-08-18T11:14:27.802Z,1282130067.802 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:14:27.802Z,1282130067.802 [CTD_Seabird] Communications Fault, FailCount= 1 2010-08-18T11:14:27.802Z,1282130067.802 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:14:28.096Z,1282130068.096 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:14:28.206Z,1282130068.206 [CTD_Seabird](INFO): Powering down 2010-08-18T11:14:29.288Z,1282130069.288 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:14:29.288Z,1282130069.288 [CTD_Seabird] No Fault, FailCount= 1 2010-08-18T11:14:29.550Z,1282130069.550 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:14:35.389Z,1282130075.389 [SBIT](IMPORTANT): Beginning Startup BIT 2010-08-18T11:14:35.394Z,1282130075.394 [CBIT](IMPORTANT): Beginning ground fault scan 2010-08-18T11:14:42.610Z,1282130082.610 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:14:42.610Z,1282130082.610 [CTD_Seabird] Communications Fault, FailCount= 2 2010-08-18T11:14:42.610Z,1282130082.610 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:14:42.684Z,1282130082.684 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:14:43.018Z,1282130083.018 [CTD_Seabird](INFO): Powering down 2010-08-18T11:14:44.291Z,1282130084.291 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:14:44.291Z,1282130084.291 [CTD_Seabird] No Fault, FailCount= 2 2010-08-18T11:14:44.358Z,1282130084.358 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:14:44.619Z,1282130084.619 [NAL9602](INFO): Powering up NAL9602 2010-08-18T11:14:46.322Z,1282130086.322 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.029310 CHAN A1 (24V): 0.000204 CHAN A2 (12V): -0.004993 CHAN A3 (5V): -0.003101 CHAN B0 (3.3V): -0.001776 CHAN B1 (3.15aV): -0.001570 CHAN B2 (3.15bV): -0.001535 CHAN B3 (GND): -0.000416 OPEN: -0.001292 Full Scale: +/- 1 mA 2010-08-18T11:14:55.523Z,1282130095.523 [NAL9602](INFO): NAL9602 initialized 2010-08-18T11:14:57.410Z,1282130097.410 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:14:57.410Z,1282130097.410 [CTD_Seabird] Communications Fault, FailCount= 3 2010-08-18T11:14:57.410Z,1282130097.410 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:14:57.619Z,1282130097.619 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:14:57.619Z,1282130097.619 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2010-08-18T11:14:57.814Z,1282130097.814 [CTD_Seabird](INFO): Powering down 2010-08-18T11:15:16.480Z,1282130116.480 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute 2010-08-18T11:15:16.484Z,1282130116.484 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2010-08-18T11:15:16.567Z,1282130116.567 [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-18T11:15:16.949Z,1282130116.949 [ThrusterHE](INFO): Resume 2010-08-18T11:15:17.527Z,1282130117.527 [ThrusterHE](INFO): Resuming 2010-08-18T11:15:17.886Z,1282130117.886 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:17.888Z,1282130117.888 [ThrusterHE](INFO): Send speed command forward:00FF 2010-08-18T11:15:18.263Z,1282130118.263 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:18.264Z,1282130118.264 [ThrusterHE](INFO): Unexpected data on serial port:Ftarting 2010-08-18T11:15:18.679Z,1282130118.679 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:18.679Z,1282130118.679 [ThrusterHE](INFO): Unexpected data on serial port:Wtarting 2010-08-18T11:15:19.058Z,1282130119.058 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:19.059Z,1282130119.059 [ThrusterHE](INFO): Unexpected data on serial port:,tarting 2010-08-18T11:15:19.478Z,1282130119.478 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:19.479Z,1282130119.479 [ThrusterHE](INFO): Unexpected data on serial port:0tarting 2010-08-18T11:15:19.898Z,1282130119.898 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:19.899Z,1282130119.899 [ThrusterHE](INFO): Unexpected data on serial port:0tarting 2010-08-18T11:15:19.902Z,1282130119.902 [ThrusterHE](INFO): Speed is bad 2010-08-18T11:15:20.358Z,1282130120.358 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:20.359Z,1282130120.359 [ThrusterHE](INFO): Unexpected data on serial port: F Starting > 2010-08-18T11:15:20.361Z,1282130120.361 [ThrusterHE](INFO): Send speed command forward:00FF 2010-08-18T11:15:20.890Z,1282130120.890 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:20.890Z,1282130120.890 [ThrusterHE](INFO): Unexpected data on serial port:>F Starting > 2010-08-18T11:15:21.041Z,1282130121.041 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:21.041Z,1282130121.041 [ThrusterHE](INFO): Unexpected data on serial port:RF Starting > 2010-08-18T11:15:21.431Z,1282130121.431 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:21.431Z,1282130121.431 [ThrusterHE](INFO): Unexpected data on serial port:WF Starting > 2010-08-18T11:15:21.846Z,1282130121.846 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:21.847Z,1282130121.847 [ThrusterHE](INFO): Unexpected data on serial port:,F Starting > 2010-08-18T11:15:22.236Z,1282130122.236 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:22.237Z,1282130122.237 [ThrusterHE](INFO): Unexpected data on serial port:7F Starting > 2010-08-18T11:15:22.239Z,1282130122.239 [ThrusterHE](INFO): Speed is bad 2010-08-18T11:15:22.643Z,1282130122.643 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:22.643Z,1282130122.643 [ThrusterHE](INFO): Unexpected data on serial port: 0036 2010-08-18T11:15:22.645Z,1282130122.645 [ThrusterHE](INFO): Send speed command forward:00FF 2010-08-18T11:15:23.055Z,1282130123.055 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:23.055Z,1282130123.055 [ThrusterHE](INFO): Unexpected data on serial port:> 0036 2010-08-18T11:15:23.455Z,1282130123.455 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:23.455Z,1282130123.455 [ThrusterHE](INFO): Unexpected data on serial port:F 0036 2010-08-18T11:15:23.859Z,1282130123.859 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:23.859Z,1282130123.859 [ThrusterHE](INFO): Unexpected data on serial port:W 0036 2010-08-18T11:15:24.262Z,1282130124.262 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:24.263Z,1282130124.263 [ThrusterHE](INFO): Unexpected data on serial port:, 0036 2010-08-18T11:15:24.265Z,1282130124.265 [ThrusterHE](INFO): Speed is bad 2010-08-18T11:15:24.667Z,1282130124.667 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:24.667Z,1282130124.667 [ThrusterHE](INFO): Unexpected data on serial port: 0FF >RW,78 2010-08-18T11:15:24.669Z,1282130124.669 [ThrusterHE](INFO): Send speed command forward:00FF 2010-08-18T11:15:25.099Z,1282130125.099 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:25.099Z,1282130125.099 [ThrusterHE](INFO): Unexpected data on serial port: 0FF >RW,78 2010-08-18T11:15:25.582Z,1282130125.582 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:25.583Z,1282130125.583 [ThrusterHE](INFO): Unexpected data on serial port:00FF >RW,78 2010-08-18T11:15:25.859Z,1282130125.859 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:25.859Z,1282130125.859 [ThrusterHE](INFO): Unexpected data on serial port:00FF >RW,78 2010-08-18T11:15:26.269Z,1282130126.269 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:26.270Z,1282130126.270 [ThrusterHE](INFO): Unexpected data on serial port:40FF >RW,78 2010-08-18T11:15:26.273Z,1282130126.273 [ThrusterHE](INFO): Speed is bad 2010-08-18T11:15:26.659Z,1282130126.659 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:26.660Z,1282130126.660 [ThrusterHE](INFO): Unexpected data on serial port:> >FW,00FF 2010-08-18T11:15:26.662Z,1282130126.662 [ThrusterHE](INFO): Send speed command forward:00FF 2010-08-18T11:15:26.964Z,1282130126.964 [CommandExec](IMPORTANT): got command maintain clear 2010-08-18T11:15:27.063Z,1282130127.063 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:27.063Z,1282130127.063 [ThrusterHE](INFO): Unexpected data on serial port:R >FW,00FF 2010-08-18T11:15:27.075Z,1282130127.075 [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-18T11:15:27.515Z,1282130127.515 [ThrusterHE](INFO): Runnable 2010-08-18T11:15:27.515Z,1282130127.515 [ThrusterHE](INFO): Unexpected data on serial port:W >FW,00FF 2010-08-18T11:15:27.898Z,1282130127.898 [ThrusterHE](INFO): Pause 2010-08-18T11:15:29.474Z,1282130129.474 [SBIT](IMPORTANT): SBIT PASSED 2010-08-18T11:15:29.474Z,1282130129.474 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2010-08-18T11:15:29.475Z,1282130129.475 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2010-08-18T11:15:29.475Z,1282130129.475 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2010-08-18T11:15:29.476Z,1282130129.476 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere; 2010-08-18T11:15:29.476Z,1282130129.476 [SBIT](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2010-08-18T11:15:29.476Z,1282130129.476 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2010-08-18T11:15:29.476Z,1282130129.476 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2010-08-18T11:15:29.476Z,1282130129.476 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour; 2010-08-18T11:15:29.476Z,1282130129.476 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt; 2010-08-18T11:15:29.476Z,1282130129.476 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool; 2010-08-18T11:15:29.476Z,1282130129.476 [SBIT](IMPORTANT): PAR_Licor.adcCal=8.76539e-06 microampere_per_count; 2010-08-18T11:15:29.477Z,1282130129.477 [SBIT](IMPORTANT): PAR_Licor.darkCount=4 count; 2010-08-18T11:15:29.477Z,1282130129.477 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2010-08-18T11:15:29.477Z,1282130129.477 [SBIT](IMPORTANT): PAR_Licor.multiplier=269.34 micromole_per_second_per_square_meter_per_microampere; 2010-08-18T11:15:29.477Z,1282130129.477 [SBIT](IMPORTANT): PAR_Licor.parCal=1.221 none; 2010-08-18T11:15:29.477Z,1282130129.477 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=235 cubic_centimeter; 2010-08-18T11:15:29.506Z,1282130129.506 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter; 2010-08-18T11:15:29.506Z,1282130129.506 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-27 millimeter; 2010-08-18T11:15:29.506Z,1282130129.506 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=37 millimeter; 2010-08-18T11:15:29.915Z,1282130129.915 [MissionManager](IMPORTANT): Started mission Startup 2010-08-18T11:15:29.915Z,1282130129.915 [Startup] Running Loop=1 2010-08-18T11:15:29.915Z,1282130129.915 [Startup](DEBUG): Aggregate::initialize Startup 2010-08-18T11:15:29.915Z,1282130129.915 [Startup:A.GoToSurface] Running Loop=1 2010-08-18T11:15:29.915Z,1282130129.915 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2010-08-18T11:15:29.916Z,1282130129.916 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2010-08-18T11:15:29.916Z,1282130129.916 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2010-08-18T11:15:29.917Z,1282130129.917 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2010-08-18T11:15:29.917Z,1282130129.917 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2010-08-18T11:15:29.918Z,1282130129.918 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2010-08-18T11:15:29.919Z,1282130129.919 [Startup:StartupSatComms] Running Loop=1 2010-08-18T11:15:29.919Z,1282130129.919 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2010-08-18T11:15:29.919Z,1282130129.919 [Startup:StartupSatComms:A] Running Loop=1 2010-08-18T11:15:30.300Z,1282130130.300 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2010-08-18T11:15:39.611Z,1282130139.611 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2010-08-18T11:16:14.714Z,1282130174.714 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:16:14.714Z,1282130174.714 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2010-08-18T11:16:14.714Z,1282130174.714 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2010-08-18T11:16:14.716Z,1282130174.716 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:16:14.716Z,1282130174.716 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2010-08-18T11:16:14.716Z,1282130174.716 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2010-08-18T11:16:14.728Z,1282130174.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2010-08-18T11:16:14.728Z,1282130174.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2010-08-18T11:16:15.142Z,1282130175.142 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2010-08-18T11:16:15.142Z,1282130175.142 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2010-08-18T11:16:15.142Z,1282130175.142 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2010-08-18T11:16:15.142Z,1282130175.142 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2010-08-18T11:16:15.526Z,1282130175.526 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2010-08-18T11:16:30.073Z,1282130190.073 [Startup:StartupSatComms:A](INFO): Timed out from 2010-08-18T11:15:29.9Z 2010-08-18T11:16:30.073Z,1282130190.073 [Startup:StartupSatComms:A] Stopped 2010-08-18T11:16:30.073Z,1282130190.073 [Startup:StartupSatComms:B] Running Loop=1 2010-08-18T11:16:30.484Z,1282130190.484 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2010-08-18T11:16:42.496Z,1282130202.496 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004877 2010-08-18T11:16:49.529Z,1282130209.529 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20100818T110938/Courier0004.lzma 2010-08-18T11:16:50.531Z,1282130210.531 [DataOverHttps](INFO): Moved sent file to Logs/20100818T110938/Courier0004.lzma.bak 2010-08-18T11:16:50.532Z,1282130210.532 [DataOverHttps](INFO): SBD MOMSN=16171248 2010-08-18T11:17:04.817Z,1282130224.817 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20100818T111405/Courier0000.lzma 2010-08-18T11:17:05.820Z,1282130225.820 [DataOverHttps](INFO): Moved sent file to Logs/20100818T111405/Courier0000.lzma.bak 2010-08-18T11:17:05.820Z,1282130225.820 [DataOverHttps](INFO): SBD MOMSN=16171250 2010-08-18T11:17:22.517Z,1282130242.517 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20100818T103638/Express0014.lzma 2010-08-18T11:17:23.519Z,1282130243.519 [DataOverHttps](INFO): Moved sent file to Logs/20100818T103638/Express0014.lzma.bak 2010-08-18T11:17:23.520Z,1282130243.520 [DataOverHttps](INFO): SBD MOMSN=16171253 2010-08-18T11:17:27.446Z,1282130247.446 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:17:27.446Z,1282130247.446 [CTD_Seabird] No Fault, FailCount= 3 2010-08-18T11:17:27.830Z,1282130247.830 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:17:30.296Z,1282130250.296 [Startup:StartupSatComms:B](INFO): Timed out from 2010-08-18T11:16:30.1Z 2010-08-18T11:17:30.296Z,1282130250.296 [Startup:StartupSatComms:B] Stopped 2010-08-18T11:17:30.296Z,1282130250.296 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2010-08-18T11:17:30.296Z,1282130250.296 [Startup:StartupSatComms] Stopped 2010-08-18T11:17:30.297Z,1282130250.297 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2010-08-18T11:17:30.297Z,1282130250.297 [Startup](INFO): Completed Startup 2010-08-18T11:17:30.298Z,1282130250.298 [MissionManager](INFO): Startup is completed. 2010-08-18T11:17:30.298Z,1282130250.298 [MissionManager](INFO): Uninitializing Mission Startup 2010-08-18T11:17:30.298Z,1282130250.298 [Startup] Stopped 2010-08-18T11:17:30.298Z,1282130250.298 [Startup](DEBUG): Aggregate::uninitialize Startup 2010-08-18T11:17:30.298Z,1282130250.298 [Startup:A.GoToSurface] Stopped 2010-08-18T11:17:30.298Z,1282130250.298 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2010-08-18T11:17:30.687Z,1282130250.687 [MissionManager](IMPORTANT): Started mission Default 2010-08-18T11:17:30.687Z,1282130250.687 [Default] Running Loop=1 2010-08-18T11:17:30.687Z,1282130250.687 [Default](DEBUG): Aggregate::initialize Default 2010-08-18T11:17:30.687Z,1282130250.687 [Default:B.GoToSurface] Running Loop=1 2010-08-18T11:17:30.687Z,1282130250.687 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2010-08-18T11:17:30.687Z,1282130250.687 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2010-08-18T11:17:30.688Z,1282130250.688 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2010-08-18T11:17:30.688Z,1282130250.688 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2010-08-18T11:17:30.688Z,1282130250.688 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2010-08-18T11:17:30.689Z,1282130250.689 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2010-08-18T11:17:30.689Z,1282130250.689 [Default:A.Wait] Running Loop=1 2010-08-18T11:17:30.689Z,1282130250.689 [Default:A.Wait](DEBUG): Initialize Wait Component. 2010-08-18T11:17:40.810Z,1282130260.810 [DataOverHttps](INFO): Sending 633 bytes from file Logs/20100818T110826/Express0001.lzma 2010-08-18T11:17:40.882Z,1282130260.882 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:17:40.882Z,1282130260.882 [CTD_Seabird] Communications Fault, FailCount= 1 2010-08-18T11:17:40.882Z,1282130260.882 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:17:41.205Z,1282130261.205 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:17:41.280Z,1282130261.280 [DataOverHttps](INFO): Moved sent file to Logs/20100818T110826/Express0001.lzma.bak 2010-08-18T11:17:41.280Z,1282130261.280 [DataOverHttps](INFO): SBD MOMSN=16171256 2010-08-18T11:17:41.286Z,1282130261.286 [CTD_Seabird](INFO): Powering down 2010-08-18T11:17:42.398Z,1282130262.398 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:17:42.398Z,1282130262.398 [CTD_Seabird] No Fault, FailCount= 1 2010-08-18T11:17:42.630Z,1282130262.630 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:17:44.004Z,1282130264.004 [Default:A.Wait](INFO): Done Waiting. 2010-08-18T11:17:44.004Z,1282130264.004 [Default:A.Wait] Stopped 2010-08-18T11:17:44.004Z,1282130264.004 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2010-08-18T11:17:44.409Z,1282130264.409 [Default:CheckIn] Running Loop=1 2010-08-18T11:17:44.410Z,1282130264.410 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2010-08-18T11:17:44.410Z,1282130264.410 [Default:CheckIn:Read_GPS] Running Loop=1 2010-08-18T11:17:44.875Z,1282130264.875 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2010-08-18T11:17:55.682Z,1282130275.682 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:17:55.682Z,1282130275.682 [CTD_Seabird] Communications Fault, FailCount= 2 2010-08-18T11:17:55.682Z,1282130275.682 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:17:55.740Z,1282130275.740 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:17:56.086Z,1282130276.086 [CTD_Seabird](INFO): Powering down 2010-08-18T11:17:57.365Z,1282130277.365 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:17:57.365Z,1282130277.365 [CTD_Seabird] No Fault, FailCount= 2 2010-08-18T11:17:57.430Z,1282130277.430 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:18:10.482Z,1282130290.482 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:18:10.482Z,1282130290.482 [CTD_Seabird] Communications Fault, FailCount= 3 2010-08-18T11:18:10.482Z,1282130290.482 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:18:10.679Z,1282130290.679 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:18:10.679Z,1282130290.679 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2010-08-18T11:18:10.886Z,1282130290.886 [CTD_Seabird](INFO): Powering down 2010-08-18T11:18:15.920Z,1282130295.920 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:18:15.920Z,1282130295.920 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2010-08-18T11:18:15.920Z,1282130295.920 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2010-08-18T11:18:15.922Z,1282130295.922 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:18:15.922Z,1282130295.922 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2010-08-18T11:18:15.922Z,1282130295.922 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2010-08-18T11:18:15.934Z,1282130295.934 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2010-08-18T11:18:15.934Z,1282130295.934 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2010-08-18T11:18:16.343Z,1282130296.343 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2010-08-18T11:18:16.343Z,1282130296.343 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2010-08-18T11:18:16.344Z,1282130296.344 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2010-08-18T11:18:16.344Z,1282130296.344 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2010-08-18T11:18:16.728Z,1282130296.728 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2010-08-18T11:18:19.204Z,1282130299.204 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2010-08-18T11:19:54.547Z,1282130394.547 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2010-08-18T11:19:59.335Z,1282130399.335 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2010-08-18T11:20:16.730Z,1282130416.730 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:20:16.730Z,1282130416.730 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2010-08-18T11:20:16.730Z,1282130416.730 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2010-08-18T11:20:16.732Z,1282130416.732 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:20:16.732Z,1282130416.732 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2010-08-18T11:20:16.732Z,1282130416.732 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2010-08-18T11:20:16.756Z,1282130416.756 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2010-08-18T11:20:16.756Z,1282130416.756 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2010-08-18T11:20:17.162Z,1282130417.162 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2010-08-18T11:20:17.163Z,1282130417.163 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2010-08-18T11:20:17.163Z,1282130417.163 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2010-08-18T11:20:17.163Z,1282130417.163 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2010-08-18T11:20:17.545Z,1282130417.545 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2010-08-18T11:20:21.608Z,1282130421.608 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2010-08-18T11:20:40.576Z,1282130440.576 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:20:40.576Z,1282130440.576 [CTD_Seabird] No Fault, FailCount= 3 2010-08-18T11:20:40.902Z,1282130440.902 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:20:53.954Z,1282130453.954 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:20:53.954Z,1282130453.954 [CTD_Seabird] Communications Fault, FailCount= 1 2010-08-18T11:20:53.954Z,1282130453.954 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:20:54.319Z,1282130454.319 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:20:54.358Z,1282130454.358 [CTD_Seabird](INFO): Powering down 2010-08-18T11:20:55.524Z,1282130455.524 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:20:55.524Z,1282130455.524 [CTD_Seabird] No Fault, FailCount= 1 2010-08-18T11:20:55.702Z,1282130455.702 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:21:08.754Z,1282130468.754 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:21:08.754Z,1282130468.754 [CTD_Seabird] Communications Fault, FailCount= 2 2010-08-18T11:21:08.754Z,1282130468.754 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:21:08.870Z,1282130468.870 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:21:09.158Z,1282130469.158 [CTD_Seabird](INFO): Powering down 2010-08-18T11:21:10.470Z,1282130470.470 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:21:10.470Z,1282130470.470 [CTD_Seabird] No Fault, FailCount= 2 2010-08-18T11:21:10.502Z,1282130470.502 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:21:23.554Z,1282130483.554 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:21:23.554Z,1282130483.554 [CTD_Seabird] Communications Fault, FailCount= 3 2010-08-18T11:21:23.554Z,1282130483.554 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:21:23.789Z,1282130483.789 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:21:23.790Z,1282130483.790 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2010-08-18T11:21:23.958Z,1282130483.958 [CTD_Seabird](INFO): Powering down 2010-08-18T11:22:17.927Z,1282130537.927 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:22:17.927Z,1282130537.927 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2010-08-18T11:22:17.927Z,1282130537.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2010-08-18T11:22:17.929Z,1282130537.929 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:22:17.929Z,1282130537.929 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2010-08-18T11:22:17.929Z,1282130537.929 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2010-08-18T11:22:17.941Z,1282130537.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2010-08-18T11:22:17.941Z,1282130537.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2010-08-18T11:22:18.363Z,1282130538.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2010-08-18T11:22:18.363Z,1282130538.363 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2010-08-18T11:22:18.363Z,1282130538.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2010-08-18T11:22:18.363Z,1282130538.363 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2010-08-18T11:22:18.740Z,1282130538.740 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2010-08-18T11:22:44.583Z,1282130564.583 [Default:CheckIn:Read_GPS](INFO): Timed out from 2010-08-18T11:17:44.4Z 2010-08-18T11:22:44.583Z,1282130564.583 [Default:CheckIn:Read_GPS] Stopped 2010-08-18T11:22:44.583Z,1282130564.583 [Default:CheckIn:Read_Iridium] Running Loop=1 2010-08-18T11:22:44.978Z,1282130564.978 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2010-08-18T11:22:51.541Z,1282130571.541 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20100818T111405/Courier0004.lzma 2010-08-18T11:22:52.544Z,1282130572.544 [DataOverHttps](INFO): Moved sent file to Logs/20100818T111405/Courier0004.lzma.bak 2010-08-18T11:22:52.544Z,1282130572.544 [DataOverHttps](INFO): SBD MOMSN=16171277 2010-08-18T11:23:09.095Z,1282130589.095 [DataOverHttps](INFO): Sending 1231 bytes from file Logs/20100818T110938/Express0001.lzma 2010-08-18T11:23:10.104Z,1282130590.104 [DataOverHttps](INFO): Moved sent file to Logs/20100818T110938/Express0001.lzma.bak 2010-08-18T11:23:10.104Z,1282130590.104 [DataOverHttps](INFO): SBD MOMSN=16171279 2010-08-18T11:23:26.674Z,1282130606.674 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20100818T110938/Express0005.lzma 2010-08-18T11:23:27.675Z,1282130607.675 [DataOverHttps](INFO): Moved sent file to Logs/20100818T110938/Express0005.lzma.bak 2010-08-18T11:23:27.676Z,1282130607.676 [DataOverHttps](INFO): SBD MOMSN=16171316 2010-08-18T11:23:44.323Z,1282130624.323 [DataOverHttps](INFO): Sending 1236 bytes from file Logs/20100818T111405/Express0001.lzma 2010-08-18T11:23:45.807Z,1282130625.807 [DataOverHttps](INFO): Moved sent file to Logs/20100818T111405/Express0001.lzma.bak 2010-08-18T11:23:45.807Z,1282130625.807 [DataOverHttps](INFO): SBD MOMSN=16171326 2010-08-18T11:23:53.674Z,1282130633.674 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:23:53.674Z,1282130633.674 [CTD_Seabird] No Fault, FailCount= 3 2010-08-18T11:23:53.974Z,1282130633.974 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:24:02.110Z,1282130642.110 [DataOverHttps](INFO): Sending 388 bytes from file Logs/20100818T111405/Express0005.lzma 2010-08-18T11:24:03.111Z,1282130643.111 [DataOverHttps](INFO): Moved sent file to Logs/20100818T111405/Express0005.lzma.bak 2010-08-18T11:24:03.112Z,1282130643.112 [DataOverHttps](INFO): SBD MOMSN=16171363 2010-08-18T11:24:07.030Z,1282130647.030 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:24:07.030Z,1282130647.030 [CTD_Seabird] Communications Fault, FailCount= 1 2010-08-18T11:24:07.030Z,1282130647.030 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:24:07.032Z,1282130647.032 [Default:CheckIn:Read_Iridium] Stopped 2010-08-18T11:24:07.032Z,1282130647.032 [Default:CheckIn:C.Wait] Running Loop=1 2010-08-18T11:24:07.032Z,1282130647.032 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2010-08-18T11:24:07.054Z,1282130647.054 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:24:07.434Z,1282130647.434 [CTD_Seabird](INFO): Powering down 2010-08-18T11:24:08.649Z,1282130648.649 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:24:08.649Z,1282130648.649 [CTD_Seabird] No Fault, FailCount= 1 2010-08-18T11:24:08.774Z,1282130648.774 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:24:16.294Z,1282130656.294 [Power24vConverter](INFO): Powering down. 2010-08-18T11:24:19.133Z,1282130659.133 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:24:19.133Z,1282130659.133 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2010-08-18T11:24:19.133Z,1282130659.133 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2010-08-18T11:24:19.139Z,1282130659.139 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-18T11:24:19.139Z,1282130659.139 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2010-08-18T11:24:19.139Z,1282130659.139 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2010-08-18T11:24:19.163Z,1282130659.163 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2010-08-18T11:24:19.163Z,1282130659.163 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2010-08-18T11:24:19.570Z,1282130659.570 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2010-08-18T11:24:19.571Z,1282130659.571 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2010-08-18T11:24:19.571Z,1282130659.571 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2010-08-18T11:24:19.571Z,1282130659.571 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2010-08-18T11:24:19.937Z,1282130659.937 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2010-08-18T11:24:21.826Z,1282130661.826 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:24:21.826Z,1282130661.826 [CTD_Seabird] Communications Fault, FailCount= 2 2010-08-18T11:24:21.826Z,1282130661.826 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:24:21.964Z,1282130661.964 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:24:22.230Z,1282130662.230 [CTD_Seabird](INFO): Powering down 2010-08-18T11:24:23.185Z,1282130663.185 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-18T11:24:23.185Z,1282130663.185 [CTD_Seabird] No Fault, FailCount= 2 2010-08-18T11:24:23.574Z,1282130663.574 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-18T11:24:25.631Z,1282130665.631 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2010-08-18T11:24:36.630Z,1282130676.630 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-18T11:24:36.630Z,1282130676.630 [CTD_Seabird] Communications Fault, FailCount= 3 2010-08-18T11:24:36.630Z,1282130676.630 [CTD_Seabird](ERROR): Communications Fault 2010-08-18T11:24:36.926Z,1282130676.926 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-18T11:24:36.926Z,1282130676.926 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2010-08-18T11:24:37.034Z,1282130677.034 [CTD_Seabird](INFO): Powering down 2010-08-18T11:24:46.147Z,1282130686.147 [CommandExec](IMPORTANT): got command restart application 2010-08-18T11:24:47.150Z,1282130687.150 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2010-08-18T11:24:47.150Z,1282130687.150 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:47.150Z,1282130687.150 [CommandExec](INFO): Uninitializing the command executive. 2010-08-18T11:24:47.150Z,1282130687.150 [CommandExec](INFO): Uninitializing the command scheduler. 2010-08-18T11:24:47.150Z,1282130687.150 [CommandExec ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:47.157Z,1282130687.157 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2010-08-18T11:24:47.158Z,1282130687.158 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2010-08-18T11:24:47.158Z,1282130687.158 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:47.158Z,1282130687.158 [NavChartDb](INFO): Join timeout helper Thread ID is 7911 2010-08-18T11:24:47.294Z,1282130687.294 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:47.294Z,1282130687.294 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:47.298Z,1282130687.298 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2010-08-18T11:24:47.298Z,1282130687.298 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:47.298Z,1282130687.298 [Radio_Surface](INFO): Join timeout helper Thread ID is 7912 2010-08-18T11:24:47.570Z,1282130687.570 [Radio_Surface](INFO): Powering down 2010-08-18T11:24:47.571Z,1282130687.571 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:47.571Z,1282130687.571 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:47.589Z,1282130687.589 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2010-08-18T11:24:47.590Z,1282130687.590 [Onboard ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:47.590Z,1282130687.590 [Onboard](INFO): Join timeout helper Thread ID is 7913 2010-08-18T11:24:47.906Z,1282130687.906 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2010-08-18T11:24:50.302Z,1282130690.302 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:50.302Z,1282130690.302 [Onboard ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:50.317Z,1282130690.317 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2010-08-18T11:24:50.318Z,1282130690.318 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:50.318Z,1282130690.318 [DataOverHttps](INFO): Join timeout helper Thread ID is 7914 2010-08-18T11:24:50.918Z,1282130690.918 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:50.918Z,1282130690.918 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:50.942Z,1282130690.942 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2010-08-18T11:24:50.942Z,1282130690.942 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:50.943Z,1282130690.943 [BackseatComponent](INFO): Join timeout helper Thread ID is 7915 2010-08-18T11:24:50.970Z,1282130690.970 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:50.970Z,1282130690.970 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:50.974Z,1282130690.974 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2010-08-18T11:24:50.975Z,1282130690.975 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:50.975Z,1282130690.975 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7916 2010-08-18T11:24:51.058Z,1282130691.058 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:51.058Z,1282130691.058 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.074Z,1282130691.074 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2010-08-18T11:24:51.074Z,1282130691.074 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.075Z,1282130691.075 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7917 2010-08-18T11:24:51.302Z,1282130691.302 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:51.302Z,1282130691.302 [CTD_Seabird](INFO): Powering down 2010-08-18T11:24:51.314Z,1282130691.314 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.318Z,1282130691.318 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2010-08-18T11:24:51.319Z,1282130691.319 [logger ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.319Z,1282130691.319 [logger](INFO): Join timeout helper Thread ID is 7918 2010-08-18T11:24:51.346Z,1282130691.346 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:51.346Z,1282130691.346 [logger ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.358Z,1282130691.358 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2010-08-18T11:24:51.358Z,1282130691.358 [CommandLine ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.359Z,1282130691.359 [CommandLine](INFO): Join timeout helper Thread ID is 7919 2010-08-18T11:24:51.430Z,1282130691.430 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:51.430Z,1282130691.430 [CommandLine ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.438Z,1282130691.438 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2010-08-18T11:24:51.438Z,1282130691.438 [CommandExec ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.439Z,1282130691.439 [CommandExec](INFO): Join timeout helper Thread ID is 7920 2010-08-18T11:24:51.450Z,1282130691.450 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2010-08-18T11:24:51.450Z,1282130691.450 [controlThread ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.451Z,1282130691.451 [controlThread](INFO): Join timeout helper Thread ID is 7921 2010-08-18T11:24:51.818Z,1282130691.818 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-18T11:24:51.818Z,1282130691.818 [controlThread](DEBUG): Uninitializing ControlThread 2010-08-18T11:24:51.819Z,1282130691.819 [NAL9602](INFO): Powering down 2010-08-18T11:24:51.821Z,1282130691.821 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2010-08-18T11:24:51.822Z,1282130691.822 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2010-08-18T11:24:51.823Z,1282130691.823 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2010-08-18T11:24:51.824Z,1282130691.824 [MissionManager](INFO): Uninitializing Mission Default 2010-08-18T11:24:51.824Z,1282130691.824 [Default] Stopped 2010-08-18T11:24:51.824Z,1282130691.824 [Default](DEBUG): Aggregate::uninitialize Default 2010-08-18T11:24:51.824Z,1282130691.824 [Default:B.GoToSurface] Stopped 2010-08-18T11:24:51.824Z,1282130691.824 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2010-08-18T11:24:51.824Z,1282130691.824 [Default:CheckIn] Stopped 2010-08-18T11:24:51.824Z,1282130691.824 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2010-08-18T11:24:51.824Z,1282130691.824 [Default:CheckIn:C.Wait] Stopped 2010-08-18T11:24:51.824Z,1282130691.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2010-08-18T11:24:51.828Z,1282130691.828 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2010-08-18T11:24:51.828Z,1282130691.828 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2010-08-18T11:24:51.828Z,1282130691.828 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2010-08-18T11:24:51.828Z,1282130691.828 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2010-08-18T11:24:51.829Z,1282130691.829 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2010-08-18T11:24:51.829Z,1282130691.829 [BuoyancyServo](INFO): Powering down 2010-08-18T11:24:51.842Z,1282130691.842 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2010-08-18T11:24:51.842Z,1282130691.842 [ElevatorServo](INFO): Powering down 2010-08-18T11:24:51.843Z,1282130691.843 [MassServo](DEBUG): Uninitialize Mass Servo. 2010-08-18T11:24:51.843Z,1282130691.843 [MassServo](INFO): Powering down 2010-08-18T11:24:51.844Z,1282130691.844 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2010-08-18T11:24:51.844Z,1282130691.844 [RudderServo](INFO): Powering down 2010-08-18T11:24:51.845Z,1282130691.845 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2010-08-18T11:24:51.845Z,1282130691.845 [ThrusterHE](INFO): Powering down 2010-08-18T11:24:51.846Z,1282130691.846 [SBIT](DEBUG): Uninitialize SBIT Component. 2010-08-18T11:24:51.846Z,1282130691.846 [IBIT](DEBUG): Uninitialize IBIT Component. 2010-08-18T11:24:51.847Z,1282130691.847 [CBIT](DEBUG): Uninitialize CBIT Component. 2010-08-18T11:24:51.847Z,1282130691.847 [CBIT](DEBUG): Powering off loads. 2010-08-18T11:24:51.857Z,1282130691.857 [CBIT](DEBUG): Disabling WDT. 2010-08-18T11:24:51.869Z,1282130691.869 [CBIT](DEBUG): Opening all GF detection circuits. 2010-08-18T11:24:51.871Z,1282130691.871 [controlThread ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.911Z,1282130691.911 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.915Z,1282130691.915 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.961Z,1282130691.961 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.963Z,1282130691.963 [Onboard ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:51.970Z,1282130691.970 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:52.015Z,1282130692.015 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:52.052Z,1282130692.052 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2010-08-18T11:24:52.116Z,1282130692.116 [logger ThreadHandler](INFO): Thread cancelled.