2010-08-16T08:06:24.527Z,1281945984.527 [Supervisor](DEBUG): Initializing supervisor. 2010-08-16T08:06:24.531Z,1281945984.531 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2010-08-16T08:06:24.532Z,1281945984.532 [SyncHandler](INFO): Protected caller Thread ID is 836 2010-08-16T08:06:24.532Z,1281945984.532 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2010-08-16T08:06:24.533Z,1281945984.533 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2010-08-16T08:06:24.533Z,1281945984.533 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837 2010-08-16T08:06:24.538Z,1281945984.538 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2010-08-16T08:06:24.557Z,1281945984.557 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2010-08-16T08:06:24.558Z,1281945984.558 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2010-08-16T08:06:24.559Z,1281945984.559 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838 2010-08-16T08:06:24.561Z,1281945984.561 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2010-08-16T08:06:24.562Z,1281945984.562 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2010-08-16T08:06:24.562Z,1281945984.562 [logger ThreadHandler](INFO): Protected caller Thread ID is 839 2010-08-16T08:06:24.566Z,1281945984.566 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2010-08-16T08:06:24.566Z,1281945984.566 [Supervisor](INFO): Looking for Config files in directory: Config/ 2010-08-16T08:06:24.571Z,1281945984.571 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2010-08-16T08:06:25.078Z,1281945985.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2010-08-16T08:06:25.080Z,1281945985.080 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2010-08-16T08:06:25.308Z,1281945985.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2010-08-16T08:06:25.309Z,1281945985.309 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2010-08-16T08:06:25.514Z,1281945985.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2010-08-16T08:06:25.514Z,1281945985.514 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2010-08-16T08:06:25.625Z,1281945985.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2010-08-16T08:06:25.626Z,1281945985.626 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2010-08-16T08:06:26.014Z,1281945986.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2010-08-16T08:06:26.016Z,1281945986.016 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2010-08-16T08:06:26.368Z,1281945986.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2010-08-16T08:06:26.369Z,1281945986.369 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2010-08-16T08:06:26.468Z,1281945986.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2010-08-16T08:06:26.470Z,1281945986.470 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2010-08-16T08:06:26.624Z,1281945986.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2010-08-16T08:06:26.625Z,1281945986.625 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2010-08-16T08:06:27.182Z,1281945987.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2010-08-16T08:06:27.184Z,1281945987.184 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2010-08-16T08:06:27.632Z,1281945987.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2010-08-16T08:06:27.872Z,1281945987.872 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2010-08-16T08:06:28.006Z,1281945988.006 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2010-08-16T08:06:28.223Z,1281945988.223 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2010-08-16T08:06:28.224Z,1281945988.224 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2010-08-16T08:06:28.355Z,1281945988.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2010-08-16T08:06:28.355Z,1281945988.355 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2010-08-16T08:06:29.062Z,1281945989.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2010-08-16T08:06:29.063Z,1281945989.063 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2010-08-16T08:06:29.144Z,1281945989.144 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2010-08-16T08:06:29.412Z,1281945989.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2010-08-16T08:06:29.414Z,1281945989.414 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2010-08-16T08:06:29.416Z,1281945989.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2010-08-16T08:06:29.640Z,1281945989.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2010-08-16T08:06:29.737Z,1281945989.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2010-08-16T08:06:29.850Z,1281945989.850 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2010-08-16T08:06:29.953Z,1281945989.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2010-08-16T08:06:30.038Z,1281945990.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2010-08-16T08:06:30.126Z,1281945990.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2010-08-16T08:06:30.242Z,1281945990.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2010-08-16T08:06:30.345Z,1281945990.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2010-08-16T08:06:30.494Z,1281945990.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2010-08-16T08:06:30.759Z,1281945990.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2010-08-16T08:06:30.760Z,1281945990.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2010-08-16T08:06:30.857Z,1281945990.857 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2010-08-16T08:06:30.878Z,1281945990.878 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2010-08-16T08:06:31.267Z,1281945991.267 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2010-08-16T08:06:31.268Z,1281945991.268 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2010-08-16T08:06:31.370Z,1281945991.370 [DeadReckonUsingMultipleVelocitySources] Loaded 2010-08-16T08:06:31.370Z,1281945991.370 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2010-08-16T08:06:31.404Z,1281945991.404 [DeadReckonUsingSpeedCalculator] Loaded 2010-08-16T08:06:31.405Z,1281945991.405 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2010-08-16T08:06:31.418Z,1281945991.418 [NavChart] Loaded 2010-08-16T08:06:31.418Z,1281945991.418 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2010-08-16T08:06:31.424Z,1281945991.424 [UniversalFixResidualReporter] Loaded 2010-08-16T08:06:31.424Z,1281945991.424 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2010-08-16T08:06:31.425Z,1281945991.425 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2010-08-16T08:06:31.425Z,1281945991.425 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2010-08-16T08:06:31.441Z,1281945991.441 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2010-08-16T08:06:31.442Z,1281945991.442 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2010-08-16T08:06:31.694Z,1281945991.694 [CTD_Seabird] Loaded 2010-08-16T08:06:31.694Z,1281945991.694 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2010-08-16T08:06:31.695Z,1281945991.695 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406214E0 2010-08-16T08:06:31.695Z,1281945991.695 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 922 2010-08-16T08:06:31.715Z,1281945991.715 [PAR_Licor] Loaded 2010-08-16T08:06:31.715Z,1281945991.715 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2010-08-16T08:06:31.746Z,1281945991.746 [WetLabsBB2FL] Loaded 2010-08-16T08:06:31.746Z,1281945991.746 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2010-08-16T08:06:31.747Z,1281945991.747 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406514E0 2010-08-16T08:06:31.748Z,1281945991.748 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 923 2010-08-16T08:06:31.749Z,1281945991.749 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2010-08-16T08:06:31.750Z,1281945991.750 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2010-08-16T08:06:31.822Z,1281945991.822 [VerticalControl](DEBUG): Construct VerticalControl. 2010-08-16T08:06:31.884Z,1281945991.884 [VerticalControl] Loaded 2010-08-16T08:06:31.884Z,1281945991.884 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2010-08-16T08:06:31.887Z,1281945991.887 [HorizontalControl](DEBUG): Construct HorizontalControl. 2010-08-16T08:06:31.926Z,1281945991.926 [HorizontalControl] Loaded 2010-08-16T08:06:31.927Z,1281945991.927 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2010-08-16T08:06:31.929Z,1281945991.929 [SpeedControl](DEBUG): Construct SpeedControl. 2010-08-16T08:06:31.932Z,1281945991.932 [SpeedControl] Loaded 2010-08-16T08:06:31.933Z,1281945991.933 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2010-08-16T08:06:31.935Z,1281945991.935 [LoopControl](DEBUG): Construct LoopControl. 2010-08-16T08:06:31.936Z,1281945991.936 [LoopControl] Loaded 2010-08-16T08:06:31.936Z,1281945991.936 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2010-08-16T08:06:31.937Z,1281945991.937 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2010-08-16T08:06:31.937Z,1281945991.937 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2010-08-16T08:06:31.999Z,1281945991.999 [DepthRateCalculator] Loaded 2010-08-16T08:06:31.999Z,1281945991.999 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2010-08-16T08:06:32.004Z,1281945992.004 [PitchRateCalculator] Loaded 2010-08-16T08:06:32.004Z,1281945992.004 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2010-08-16T08:06:32.014Z,1281945992.014 [SpeedCalculator] Loaded 2010-08-16T08:06:32.014Z,1281945992.014 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2010-08-16T08:06:32.028Z,1281945992.028 [TempGradientCalculator] Loaded 2010-08-16T08:06:32.028Z,1281945992.028 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2010-08-16T08:06:32.032Z,1281945992.032 [YawRateCalculator] Loaded 2010-08-16T08:06:32.033Z,1281945992.033 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2010-08-16T08:06:32.051Z,1281945992.051 [ElevatorOffsetCalculator] Loaded 2010-08-16T08:06:32.051Z,1281945992.051 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2010-08-16T08:06:32.051Z,1281945992.051 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2010-08-16T08:06:32.052Z,1281945992.052 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2010-08-16T08:06:32.176Z,1281945992.176 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2010-08-16T08:06:32.177Z,1281945992.177 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2010-08-16T08:06:32.261Z,1281945992.261 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2010-08-16T08:06:32.262Z,1281945992.262 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2010-08-16T08:06:32.423Z,1281945992.423 [BuoyancyServo] Loaded 2010-08-16T08:06:32.424Z,1281945992.424 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2010-08-16T08:06:32.443Z,1281945992.443 [ElevatorServo] Loaded 2010-08-16T08:06:32.443Z,1281945992.443 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2010-08-16T08:06:32.463Z,1281945992.463 [MassServo] Loaded 2010-08-16T08:06:32.463Z,1281945992.463 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2010-08-16T08:06:32.481Z,1281945992.481 [RudderServo] Loaded 2010-08-16T08:06:32.482Z,1281945992.482 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2010-08-16T08:06:32.496Z,1281945992.496 [ThrusterHE] Loaded 2010-08-16T08:06:32.497Z,1281945992.497 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2010-08-16T08:06:32.497Z,1281945992.497 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2010-08-16T08:06:32.498Z,1281945992.498 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2010-08-16T08:06:32.634Z,1281945992.634 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2010-08-16T08:06:32.635Z,1281945992.635 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2010-08-16T08:06:33.755Z,1281945993.755 [AHRS_M2] Loaded 2010-08-16T08:06:33.755Z,1281945993.755 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2010-08-16T08:06:34.000Z,1281945994.000 [BackseatComponent] Loaded 2010-08-16T08:06:34.001Z,1281945994.001 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2010-08-16T08:06:34.002Z,1281945994.002 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0 2010-08-16T08:06:34.002Z,1281945994.002 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924 2010-08-16T08:06:34.005Z,1281945994.005 [LcmUniversalReporter] Loaded 2010-08-16T08:06:34.005Z,1281945994.005 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2010-08-16T08:06:34.833Z,1281945994.833 [BPC1] Loaded 2010-08-16T08:06:34.833Z,1281945994.833 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2010-08-16T08:06:35.161Z,1281945995.161 [DataOverHttps] Loaded 2010-08-16T08:06:35.162Z,1281945995.162 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2010-08-16T08:06:35.163Z,1281945995.163 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0 2010-08-16T08:06:35.163Z,1281945995.163 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925 2010-08-16T08:06:35.183Z,1281945995.183 [Depth_Keller] Loaded 2010-08-16T08:06:35.183Z,1281945995.183 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2010-08-16T08:06:35.244Z,1281945995.244 [NAL9602] Loaded 2010-08-16T08:06:35.244Z,1281945995.244 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2010-08-16T08:06:35.274Z,1281945995.274 [Onboard] Loaded 2010-08-16T08:06:35.274Z,1281945995.274 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2010-08-16T08:06:35.275Z,1281945995.275 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A794E0 2010-08-16T08:06:35.276Z,1281945995.276 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926 2010-08-16T08:06:35.289Z,1281945995.289 [Power24vConverter] Loaded 2010-08-16T08:06:35.289Z,1281945995.289 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2010-08-16T08:06:35.302Z,1281945995.302 [Radio_Surface] Loaded 2010-08-16T08:06:35.302Z,1281945995.302 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2010-08-16T08:06:35.303Z,1281945995.303 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2010-08-16T08:06:35.304Z,1281945995.304 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927 2010-08-16T08:06:35.305Z,1281945995.305 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2010-08-16T08:06:35.305Z,1281945995.305 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2010-08-16T08:06:35.486Z,1281945995.486 [SBIT](DEBUG): Construct Startup Built In Test. 2010-08-16T08:06:35.495Z,1281945995.495 [SBIT] Loaded 2010-08-16T08:06:35.496Z,1281945995.496 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2010-08-16T08:06:35.499Z,1281945995.499 [IBIT](DEBUG): Construct Initiated Built In Test. 2010-08-16T08:06:35.512Z,1281945995.512 [IBIT] Loaded 2010-08-16T08:06:35.512Z,1281945995.512 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2010-08-16T08:06:35.518Z,1281945995.518 [CBIT](DEBUG): Construct Continuous Built In Test. 2010-08-16T08:06:35.622Z,1281945995.622 [CBIT] Loaded 2010-08-16T08:06:35.622Z,1281945995.622 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2010-08-16T08:06:35.622Z,1281945995.622 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2010-08-16T08:06:35.623Z,1281945995.623 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2010-08-16T08:06:35.666Z,1281945995.666 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2010-08-16T08:06:35.672Z,1281945995.672 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2010-08-16T08:06:35.675Z,1281945995.675 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2010-08-16T08:06:35.686Z,1281945995.686 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2010-08-16T08:06:35.687Z,1281945995.687 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B354E0 2010-08-16T08:06:35.687Z,1281945995.687 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928 2010-08-16T08:06:35.692Z,1281945995.692 [Supervisor](INFO): Main Thread ID is 832 2010-08-16T08:06:35.692Z,1281945995.692 [Supervisor](DEBUG): Running supervisor. 2010-08-16T08:06:35.692Z,1281945995.692 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929 2010-08-16T08:06:35.695Z,1281945995.695 [controlThread ThreadHandler](INFO): Handler Thread ID is 930 2010-08-16T08:06:35.695Z,1281945995.695 [controlThread](DEBUG): Initializing ControlThread 2010-08-16T08:06:35.697Z,1281945995.697 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2010-08-16T08:06:35.697Z,1281945995.697 [NavChart](DEBUG): Initialize NavChart Navigation. 2010-08-16T08:06:35.698Z,1281945995.698 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2010-08-16T08:06:35.702Z,1281945995.702 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2010-08-16T08:06:35.704Z,1281945995.704 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2010-08-16T08:06:35.704Z,1281945995.704 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2010-08-16T08:06:35.705Z,1281945995.705 [LoopControl](DEBUG): Initialize LoopControlComponent. 2010-08-16T08:06:35.705Z,1281945995.705 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2010-08-16T08:06:35.706Z,1281945995.706 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2010-08-16T08:06:35.706Z,1281945995.706 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2010-08-16T08:06:35.707Z,1281945995.707 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2010-08-16T08:06:35.707Z,1281945995.707 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2010-08-16T08:06:35.708Z,1281945995.708 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2010-08-16T08:06:35.711Z,1281945995.711 [SBIT](INFO): Initialize SBIT Component. 2010-08-16T08:06:35.712Z,1281945995.712 [SBIT](IMPORTANT): git: 2021-09-29 2010-08-16T08:06:35.712Z,1281945995.712 [SBIT](INFO): git hash: d0bedea221735b48726a30f35f8acb1d414c6dbf 2010-08-16T08:06:35.713Z,1281945995.713 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2010-08-16T08:06:35.714Z,1281945995.714 [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-16T08:06:35.715Z,1281945995.715 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2010-08-16T08:06:35.716Z,1281945995.716 [IBIT](INFO): Initialize IBIT Component. 2010-08-16T08:06:35.717Z,1281945995.717 [CBIT](DEBUG): Initialize CBIT Component. 2010-08-16T08:06:35.718Z,1281945995.718 [logger ThreadHandler](INFO): Handler Thread ID is 931 2010-08-16T08:06:35.730Z,1281945995.730 [CBIT](DEBUG): Initialized mux pins. 2010-08-16T08:06:35.730Z,1281945995.730 [CBIT](DEBUG): Initializing the watchdog timer. 2010-08-16T08:06:35.738Z,1281945995.738 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 932 2010-08-16T08:06:35.739Z,1281945995.739 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-16T08:06:35.743Z,1281945995.743 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 934 2010-08-16T08:06:35.746Z,1281945995.746 [WetLabsBB2FL](INFO): Powering up 2010-08-16T08:06:35.747Z,1281945995.747 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936 2010-08-16T08:06:35.754Z,1281945995.754 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2010-08-16T08:06:35.754Z,1281945995.754 [CBIT](DEBUG): Initializing heartbeat. 2010-08-16T08:06:35.755Z,1281945995.755 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937 2010-08-16T08:06:35.756Z,1281945995.756 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2010-08-16T08:06:35.762Z,1281945995.762 [Onboard ThreadHandler](INFO): Handler Thread ID is 938 2010-08-16T08:06:35.780Z,1281945995.780 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939 2010-08-16T08:06:35.798Z,1281945995.798 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940 2010-08-16T08:06:35.807Z,1281945995.807 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2010-08-16T08:06:35.807Z,1281945995.807 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2010-08-16T08:06:35.808Z,1281945995.808 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2010-08-16T08:06:35.808Z,1281945995.808 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2010-08-16T08:06:35.808Z,1281945995.808 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2010-08-16T08:06:35.808Z,1281945995.808 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2010-08-16T08:06:35.808Z,1281945995.808 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2010-08-16T08:06:35.808Z,1281945995.808 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2010-08-16T08:06:35.809Z,1281945995.809 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2010-08-16T08:06:35.809Z,1281945995.809 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2010-08-16T08:06:35.809Z,1281945995.809 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2010-08-16T08:06:35.809Z,1281945995.809 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2010-08-16T08:06:35.809Z,1281945995.809 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2010-08-16T08:06:35.810Z,1281945995.810 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2010-08-16T08:06:35.810Z,1281945995.810 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2010-08-16T08:06:35.810Z,1281945995.810 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2010-08-16T08:06:35.826Z,1281945995.826 [CBIT](DEBUG): Deactivating GF circuits. 2010-08-16T08:06:35.826Z,1281945995.826 [CBIT](DEBUG): Deactivating emergency mode. 2010-08-16T08:06:35.861Z,1281945995.861 [CBIT](DEBUG): Backplane powered. 2010-08-16T08:06:35.863Z,1281945995.863 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2010-08-16T08:06:35.898Z,1281945995.898 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2010-08-16T08:06:35.926Z,1281945995.926 [MissionManager](DEBUG): 2010-08-16T08:06:35.927Z,1281945995.927 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2010-08-16T08:06:35.000Z,1281945996.000 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2010-08-16T08:06:36.001Z,1281945996.001 [Default:A.Wait](DEBUG): Construct Wait. 2010-08-16T08:06:36.029Z,1281945996.029 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2010-08-16T08:06:36.071Z,1281945996.071 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2010-08-16T08:06:36.094Z,1281945996.094 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2010-08-16T08:06:36.100Z,1281945996.100 [Default:E.Execute](DEBUG): Construct Execute. 2010-08-16T08:06:36.120Z,1281945996.120 [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-16T08:06:36.125Z,1281945996.125 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,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-16T08:06:36.139Z,1281945996.139 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2010-08-16T08:06:36.186Z,1281945996.186 [Radio_Surface](INFO): Powering up 2010-08-16T08:06:36.239Z,1281945996.239 [Power24vConverter](INFO): Powering up. 2010-08-16T08:06:36.272Z,1281945996.272 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2010-08-16T08:06:36.278Z,1281945996.278 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2010-08-16T08:06:36.279Z,1281945996.279 [ElevatorServo](DEBUG): Initializing EZServoServo. 2010-08-16T08:06:36.286Z,1281945996.286 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2010-08-16T08:06:36.287Z,1281945996.287 [MassServo](DEBUG): Initializing EZServoServo. 2010-08-16T08:06:36.298Z,1281945996.298 [MassServo](DEBUG): Initializing MassServo. 2010-08-16T08:06:36.299Z,1281945996.299 [RudderServo](DEBUG): Initializing EZServoServo. 2010-08-16T08:06:36.306Z,1281945996.306 [RudderServo](DEBUG): Initializing RudderServo. 2010-08-16T08:06:36.307Z,1281945996.307 [ThrusterHE](DEBUG): Initializing EZServoServo. 2010-08-16T08:06:36.314Z,1281945996.314 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2010-08-16T08:06:37.262Z,1281945997.262 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2010-08-16T08:06:37.262Z,1281945997.262 [RudderServo](FAULT): Rudder failed to initialize 2010-08-16T08:06:37.262Z,1281945997.262 [RudderServo] Communications Fault, FailCount= 1 2010-08-16T08:06:37.262Z,1281945997.262 [RudderServo](ERROR): Communications Fault 2010-08-16T08:06:37.266Z,1281945997.266 [CBIT](ERROR): Communications Fault in component: RudderServo 2010-08-16T08:06:37.515Z,1281945997.515 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2010-08-16T08:06:37.516Z,1281945997.516 [RudderServo](INFO): Powering down 2010-08-16T08:06:38.171Z,1281945998.171 [RudderServo](DEBUG): Initializing EZServoServo. 2010-08-16T08:06:38.291Z,1281945998.291 [RudderServo](DEBUG): Initializing RudderServo. 2010-08-16T08:06:38.297Z,1281945998.297 [CBIT](INFO): Clearing failed state for component RudderServo 2010-08-16T08:06:38.297Z,1281945998.297 [RudderServo] No Fault, FailCount= 1 2010-08-16T08:06:41.034Z,1281946001.034 [WetLabsBB2FL] Communications Fault, FailCount= 1 2010-08-16T08:06:41.034Z,1281946001.034 [WetLabsBB2FL](ERROR): Communications Fault 2010-08-16T08:06:41.041Z,1281946001.041 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2010-08-16T08:06:41.534Z,1281946001.534 [WetLabsBB2FL](INFO): Powering down 2010-08-16T08:06:42.390Z,1281946002.390 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2010-08-16T08:06:42.390Z,1281946002.390 [WetLabsBB2FL] No Fault, FailCount= 1 2010-08-16T08:06:42.747Z,1281946002.747 [WetLabsBB2FL](INFO): Powering up 2010-08-16T08:06:48.038Z,1281946008.038 [WetLabsBB2FL] Communications Fault, FailCount= 2 2010-08-16T08:06:48.038Z,1281946008.038 [WetLabsBB2FL](ERROR): Communications Fault 2010-08-16T08:06:48.309Z,1281946008.309 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2010-08-16T08:06:48.538Z,1281946008.538 [WetLabsBB2FL](INFO): Powering down 2010-08-16T08:06:48.790Z,1281946008.790 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-16T08:06:48.790Z,1281946008.790 [CTD_Seabird] Communications Fault, FailCount= 1 2010-08-16T08:06:48.790Z,1281946008.790 [CTD_Seabird](ERROR): Communications Fault 2010-08-16T08:06:49.128Z,1281946009.128 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-16T08:06:49.194Z,1281946009.194 [CTD_Seabird](INFO): Powering down 2010-08-16T08:06:49.528Z,1281946009.528 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2010-08-16T08:06:49.528Z,1281946009.528 [WetLabsBB2FL] No Fault, FailCount= 2 2010-08-16T08:06:49.751Z,1281946009.751 [WetLabsBB2FL](INFO): Powering up 2010-08-16T08:06:50.336Z,1281946010.336 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-16T08:06:50.336Z,1281946010.336 [CTD_Seabird] No Fault, FailCount= 1 2010-08-16T08:06:50.538Z,1281946010.538 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-16T08:06:54.322Z,1281946014.322 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2010-08-16T08:06:54.322Z,1281946014.322 [AHRS_M2] Data Fault, FailCount= 1 2010-08-16T08:06:54.322Z,1281946014.322 [AHRS_M2](ERROR): Data Fault 2010-08-16T08:06:54.378Z,1281946014.378 [CBIT](ERROR): Data Fault in component: AHRS_M2 2010-08-16T08:06:54.714Z,1281946014.714 [AHRS_M2](INFO): Powering down 2010-08-16T08:06:55.041Z,1281946015.041 [WetLabsBB2FL] Communications Fault, FailCount= 3 2010-08-16T08:06:55.041Z,1281946015.041 [WetLabsBB2FL](ERROR): Communications Fault 2010-08-16T08:06:55.159Z,1281946015.159 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2010-08-16T08:06:55.542Z,1281946015.542 [WetLabsBB2FL](INFO): Powering down 2010-08-16T08:06:55.578Z,1281946015.578 [CBIT](INFO): Clearing failed state for component AHRS_M2 2010-08-16T08:06:55.578Z,1281946015.578 [AHRS_M2] No Fault, FailCount= 1 2010-08-16T08:06:55.926Z,1281946015.926 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2010-08-16T08:06:56.376Z,1281946016.376 [SBIT](IMPORTANT): Beginning Startup BIT 2010-08-16T08:06:56.381Z,1281946016.381 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2010-08-16T08:06:56.381Z,1281946016.381 [WetLabsBB2FL] No Fault, FailCount= 3 2010-08-16T08:06:56.381Z,1281946016.381 [CBIT](IMPORTANT): Beginning ground fault scan 2010-08-16T08:06:56.755Z,1281946016.755 [WetLabsBB2FL](INFO): Powering up 2010-08-16T08:06:59.482Z,1281946019.482 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:00.022Z,1281946020.022 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:00.570Z,1281946020.570 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:00.986Z,1281946020.986 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:01.326Z,1281946021.326 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:02.045Z,1281946022.045 [WetLabsBB2FL] Communications Fault, FailCount= 4 2010-08-16T08:07:02.045Z,1281946022.045 [WetLabsBB2FL](ERROR): Communications Fault 2010-08-16T08:07:02.176Z,1281946022.176 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2010-08-16T08:07:02.546Z,1281946022.546 [WetLabsBB2FL](INFO): Powering down 2010-08-16T08:07:03.590Z,1281946023.590 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-16T08:07:03.590Z,1281946023.590 [CTD_Seabird] Communications Fault, FailCount= 2 2010-08-16T08:07:03.590Z,1281946023.590 [CTD_Seabird](ERROR): Communications Fault 2010-08-16T08:07:03.719Z,1281946023.719 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-16T08:07:03.719Z,1281946023.719 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2010-08-16T08:07:03.719Z,1281946023.719 [WetLabsBB2FL] No Fault, FailCount= 4 2010-08-16T08:07:03.759Z,1281946023.759 [WetLabsBB2FL](INFO): Powering up 2010-08-16T08:07:03.994Z,1281946023.994 [CTD_Seabird](INFO): Powering down 2010-08-16T08:07:04.932Z,1281946024.932 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2010-08-16T08:07:04.932Z,1281946024.932 [CTD_Seabird] No Fault, FailCount= 2 2010-08-16T08:07:05.267Z,1281946025.267 [NAL9602](INFO): Powering up NAL9602 2010-08-16T08:07:05.346Z,1281946025.346 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2010-08-16T08:07:08.137Z,1281946028.137 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006646 CHAN A1 (24V): 0.007610 CHAN A2 (12V): -0.005402 CHAN A3 (5V): -0.002663 CHAN B0 (3.3V): -0.001432 CHAN B1 (3.15aV): -0.001341 CHAN B2 (3.15bV): -0.000485 CHAN B3 (GND): 0.000171 OPEN: 0.005485 Full Scale: +/- 1 mA 2010-08-16T08:07:09.053Z,1281946029.053 [WetLabsBB2FL] Communications Fault, FailCount= 5 2010-08-16T08:07:09.054Z,1281946029.054 [WetLabsBB2FL](ERROR): Communications Fault 2010-08-16T08:07:09.337Z,1281946029.337 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2010-08-16T08:07:09.337Z,1281946029.337 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2010-08-16T08:07:09.554Z,1281946029.554 [WetLabsBB2FL](INFO): Powering down 2010-08-16T08:07:11.725Z,1281946031.725 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2010-08-16T08:07:11.726Z,1281946031.726 [AHRS_M2] Data Fault, FailCount= 2 2010-08-16T08:07:11.726Z,1281946031.726 [AHRS_M2](ERROR): Data Fault 2010-08-16T08:07:11.978Z,1281946031.978 [CBIT](ERROR): Data Fault in component: AHRS_M2 2010-08-16T08:07:12.118Z,1281946032.118 [AHRS_M2](INFO): Powering down 2010-08-16T08:07:13.058Z,1281946033.058 [CBIT](INFO): Clearing failed state for component AHRS_M2 2010-08-16T08:07:13.058Z,1281946033.058 [AHRS_M2] No Fault, FailCount= 2 2010-08-16T08:07:13.330Z,1281946033.330 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2010-08-16T08:07:16.163Z,1281946036.163 [NAL9602](INFO): NAL9602 initialized 2010-08-16T08:07:16.662Z,1281946036.662 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:17.062Z,1281946037.062 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:17.462Z,1281946037.462 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:17.870Z,1281946037.870 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:18.278Z,1281946038.278 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:18.402Z,1281946038.402 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2010-08-16T08:07:18.402Z,1281946038.402 [CTD_Seabird] Communications Fault, FailCount= 3 2010-08-16T08:07:18.402Z,1281946038.402 [CTD_Seabird](ERROR): Communications Fault 2010-08-16T08:07:18.678Z,1281946038.678 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:18.747Z,1281946038.747 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2010-08-16T08:07:18.747Z,1281946038.747 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2010-08-16T08:07:18.806Z,1281946038.806 [CTD_Seabird](INFO): Powering down 2010-08-16T08:07:19.082Z,1281946039.082 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:19.486Z,1281946039.486 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:19.890Z,1281946039.890 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:20.294Z,1281946040.294 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:20.698Z,1281946040.698 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:21.102Z,1281946041.102 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:21.494Z,1281946041.494 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:21.910Z,1281946041.910 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:22.322Z,1281946042.322 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:22.718Z,1281946042.718 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:23.122Z,1281946043.122 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:23.534Z,1281946043.534 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:23.930Z,1281946043.930 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:24.334Z,1281946044.334 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:24.738Z,1281946044.738 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:25.146Z,1281946045.146 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:25.550Z,1281946045.550 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:25.954Z,1281946045.954 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:26.362Z,1281946046.362 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:26.362Z,1281946046.362 [AHRS_M2](FAULT): Failed to initialize within timeout. 2010-08-16T08:07:26.362Z,1281946046.362 [AHRS_M2] Communications Fault, FailCount= 3 2010-08-16T08:07:26.362Z,1281946046.362 [AHRS_M2](ERROR): Communications Fault 2010-08-16T08:07:26.431Z,1281946046.431 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2010-08-16T08:07:26.661Z,1281946046.661 [AHRS_M2](INFO): Powering down 2010-08-16T08:07:27.507Z,1281946047.507 [CBIT](INFO): Clearing failed state for component AHRS_M2 2010-08-16T08:07:27.507Z,1281946047.507 [AHRS_M2] No Fault, FailCount= 3 2010-08-16T08:07:27.874Z,1281946047.874 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2010-08-16T08:07:31.186Z,1281946051.186 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2010-08-16T08:07:41.689Z,1281946061.689 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2010-08-16T08:07:41.690Z,1281946061.690 [AHRS_M2] Data Fault, FailCount= 4 2010-08-16T08:07:41.690Z,1281946061.690 [AHRS_M2](ERROR): Data Fault 2010-08-16T08:07:41.991Z,1281946061.991 [CBIT](ERROR): Data Fault in component: AHRS_M2 2010-08-16T08:07:42.025Z,1281946062.025 [AHRS_M2](INFO): Powering down 2010-08-16T08:07:42.871Z,1281946062.871 [CBIT](INFO): Clearing failed state for component AHRS_M2 2010-08-16T08:07:42.871Z,1281946062.871 [AHRS_M2] No Fault, FailCount= 4 2010-08-16T08:07:43.230Z,1281946063.230 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2010-08-16T08:07:50.541Z,1281946070.541 [SBIT](IMPORTANT): SBIT PASSED 2010-08-16T08:07:50.559Z,1281946070.559 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2010-08-16T08:07:50.560Z,1281946070.560 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2010-08-16T08:07:50.560Z,1281946070.560 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2010-08-16T08:07:50.560Z,1281946070.560 [SBIT](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2010-08-16T08:07:50.561Z,1281946070.561 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2010-08-16T08:07:50.561Z,1281946070.561 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2010-08-16T08:07:50.561Z,1281946070.561 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour; 2010-08-16T08:07:50.561Z,1281946070.561 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt; 2010-08-16T08:07:50.561Z,1281946070.561 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool; 2010-08-16T08:07:50.561Z,1281946070.561 [SBIT](IMPORTANT): PAR_Licor.adcCal=8.76539e-06 microampere_per_count; 2010-08-16T08:07:50.561Z,1281946070.561 [SBIT](IMPORTANT): PAR_Licor.darkCount=4 count; 2010-08-16T08:07:50.562Z,1281946070.562 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2010-08-16T08:07:50.562Z,1281946070.562 [SBIT](IMPORTANT): PAR_Licor.multiplier=269.34 micromole_per_second_per_square_meter_per_microampere; 2010-08-16T08:07:50.562Z,1281946070.562 [SBIT](IMPORTANT): PAR_Licor.parCal=1.221 none; 2010-08-16T08:07:50.562Z,1281946070.562 [SBIT](IMPORTANT): ThrusterHE.loadAtStartup=1 bool; 2010-08-16T08:07:50.562Z,1281946070.562 [SBIT](IMPORTANT): ThrusterHE.simulateHardware=0 bool; 2010-08-16T08:07:50.562Z,1281946070.562 [SBIT](IMPORTANT): ThrusterServo.loadAtStartup=0 bool; 2010-08-16T08:07:50.562Z,1281946070.562 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=235 cubic_centimeter; 2010-08-16T08:07:50.563Z,1281946070.563 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter; 2010-08-16T08:07:50.563Z,1281946070.563 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-26 millimeter; 2010-08-16T08:07:50.563Z,1281946070.563 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter; 2010-08-16T08:07:50.947Z,1281946070.947 [MissionManager](IMPORTANT): Started mission Startup 2010-08-16T08:07:50.948Z,1281946070.948 [Startup] Running Loop=1 2010-08-16T08:07:50.948Z,1281946070.948 [Startup](DEBUG): Aggregate::initialize Startup 2010-08-16T08:07:50.948Z,1281946070.948 [Startup:A.GoToSurface] Running Loop=1 2010-08-16T08:07:50.948Z,1281946070.948 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2010-08-16T08:07:50.949Z,1281946070.949 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2010-08-16T08:07:50.949Z,1281946070.949 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2010-08-16T08:07:50.950Z,1281946070.950 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2010-08-16T08:07:50.950Z,1281946070.950 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2010-08-16T08:07:50.951Z,1281946070.951 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2010-08-16T08:07:50.952Z,1281946070.952 [Startup:StartupSatComms] Running Loop=1 2010-08-16T08:07:50.952Z,1281946070.952 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2010-08-16T08:07:50.952Z,1281946070.952 [Startup:StartupSatComms:A] Running Loop=1 2010-08-16T08:07:51.356Z,1281946071.356 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2010-08-16T08:07:56.573Z,1281946076.573 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2010-08-16T08:07:56.574Z,1281946076.574 [AHRS_M2] Data Fault, FailCount= 5 2010-08-16T08:07:56.574Z,1281946076.574 [AHRS_M2](ERROR): Data Fault 2010-08-16T08:07:56.654Z,1281946076.654 [CBIT](ERROR): Data Fault in component: AHRS_M2 2010-08-16T08:07:56.654Z,1281946076.654 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2010-08-16T08:07:56.962Z,1281946076.962 [AHRS_M2](INFO): Powering down 2010-08-16T08:07:57.091Z,1281946077.091 [CommandLine](FAULT): Scheduling is paused 2010-08-16T08:07:57.091Z,1281946077.091 [CBIT](INFO): Critical error at 20100816T080756 2010-08-16T08:07:57.092Z,1281946077.092 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2010-08-16T08:08:35.758Z,1281946115.758 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-16T08:08:35.758Z,1281946115.758 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2010-08-16T08:08:35.758Z,1281946115.758 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2010-08-16T08:08:35.760Z,1281946115.760 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2010-08-16T08:08:35.760Z,1281946115.760 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2010-08-16T08:08:35.760Z,1281946115.760 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2010-08-16T08:08:35.772Z,1281946115.772 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2010-08-16T08:08:35.772Z,1281946115.772 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2010-08-16T08:08:36.174Z,1281946116.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2010-08-16T08:08:36.174Z,1281946116.174 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2010-08-16T08:08:36.174Z,1281946116.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2010-08-16T08:08:36.174Z,1281946116.174 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2010-08-16T08:08:36.623Z,1281946116.623 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2010-08-16T08:08:51.112Z,1281946131.112 [Startup:StartupSatComms:A](INFO): Timed out from 2010-08-16T08:07:50.0Z 2010-08-16T08:08:51.112Z,1281946131.112 [Startup:StartupSatComms:A] Stopped 2010-08-16T08:08:51.112Z,1281946131.112 [Startup:StartupSatComms:B] Running Loop=1 2010-08-16T08:08:51.516Z,1281946131.516 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2010-08-16T08:08:56.247Z,1281946136.247 [CommandLine](IMPORTANT): got command quit 2010-08-16T08:08:57.254Z,1281946137.254 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:08:57.254Z,1281946137.254 [CommandLine ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:08:57.297Z,1281946137.297 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2010-08-16T08:08:57.298Z,1281946137.298 [CommandLine ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:08:57.304Z,1281946137.304 [CommandLine](INFO): Join timeout helper Thread ID is 976 2010-08-16T08:08:57.322Z,1281946137.322 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2010-08-16T08:08:57.322Z,1281946137.322 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:08:57.322Z,1281946137.322 [NavChartDb](INFO): Join timeout helper Thread ID is 977 2010-08-16T08:08:57.714Z,1281946137.714 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:08:57.714Z,1281946137.714 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:08:57.718Z,1281946137.718 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2010-08-16T08:08:57.718Z,1281946137.718 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:08:57.718Z,1281946137.718 [Radio_Surface](INFO): Join timeout helper Thread ID is 978 2010-08-16T08:08:58.066Z,1281946138.066 [Radio_Surface](INFO): Powering down 2010-08-16T08:08:58.067Z,1281946138.067 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:08:58.067Z,1281946138.067 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:08:58.070Z,1281946138.070 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2010-08-16T08:08:58.070Z,1281946138.070 [Onboard ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:08:58.070Z,1281946138.070 [Onboard](INFO): Join timeout helper Thread ID is 979 2010-08-16T08:09:00.878Z,1281946140.878 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:09:00.878Z,1281946140.878 [Onboard ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:00.882Z,1281946140.882 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2010-08-16T08:09:00.882Z,1281946140.882 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:00.882Z,1281946140.882 [DataOverHttps](INFO): Join timeout helper Thread ID is 980 2010-08-16T08:09:01.666Z,1281946141.666 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:09:01.670Z,1281946141.670 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.678Z,1281946141.678 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2010-08-16T08:09:01.679Z,1281946141.679 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.679Z,1281946141.679 [BackseatComponent](INFO): Join timeout helper Thread ID is 981 2010-08-16T08:09:01.718Z,1281946141.718 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:09:01.718Z,1281946141.718 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.738Z,1281946141.738 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2010-08-16T08:09:01.738Z,1281946141.738 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.739Z,1281946141.739 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 982 2010-08-16T08:09:01.869Z,1281946141.869 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:09:01.870Z,1281946141.870 [WetLabsBB2FL](INFO): Powering down 2010-08-16T08:09:01.871Z,1281946141.871 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.878Z,1281946141.878 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2010-08-16T08:09:01.878Z,1281946141.878 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.879Z,1281946141.879 [CTD_Seabird](INFO): Join timeout helper Thread ID is 983 2010-08-16T08:09:01.957Z,1281946141.957 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:09:01.958Z,1281946141.958 [CTD_Seabird](INFO): Powering down 2010-08-16T08:09:01.969Z,1281946141.969 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.978Z,1281946141.978 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2010-08-16T08:09:01.978Z,1281946141.978 [logger ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.979Z,1281946141.979 [logger](INFO): Join timeout helper Thread ID is 984 2010-08-16T08:09:01.994Z,1281946141.994 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:09:01.994Z,1281946141.994 [logger ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.998Z,1281946141.998 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2010-08-16T08:09:01.999Z,1281946141.999 [CommandLine ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.999Z,1281946141.999 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2010-08-16T08:09:01.999Z,1281946141.999 [controlThread ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:01.999Z,1281946141.999 [controlThread](INFO): Join timeout helper Thread ID is 985 2010-08-16T08:09:01.000Z,1281946142.000 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2010-08-16T08:09:01.000Z,1281946142.000 [controlThread](DEBUG): Uninitializing ControlThread 2010-08-16T08:09:02.000Z,1281946142.000 [AHRS_M2](INFO): Powering down 2010-08-16T08:09:02.002Z,1281946142.002 [NAL9602](INFO): Powering down 2010-08-16T08:09:02.004Z,1281946142.004 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2010-08-16T08:09:02.005Z,1281946142.005 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2010-08-16T08:09:02.006Z,1281946142.006 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2010-08-16T08:09:02.007Z,1281946142.007 [MissionManager](INFO): Uninitializing Mission Default 2010-08-16T08:09:02.010Z,1281946142.010 [MissionManager](INFO): Uninitializing Mission Startup 2010-08-16T08:09:02.010Z,1281946142.010 [Startup] Stopped 2010-08-16T08:09:02.010Z,1281946142.010 [Startup](DEBUG): Aggregate::uninitialize Startup 2010-08-16T08:09:02.010Z,1281946142.010 [Startup:A.GoToSurface] Stopped 2010-08-16T08:09:02.010Z,1281946142.010 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2010-08-16T08:09:02.010Z,1281946142.010 [Startup:StartupSatComms] Stopped 2010-08-16T08:09:02.010Z,1281946142.010 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2010-08-16T08:09:02.010Z,1281946142.010 [Startup:StartupSatComms:B] Stopped 2010-08-16T08:09:02.012Z,1281946142.012 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2010-08-16T08:09:02.013Z,1281946142.013 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2010-08-16T08:09:02.013Z,1281946142.013 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2010-08-16T08:09:02.013Z,1281946142.013 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2010-08-16T08:09:02.014Z,1281946142.014 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2010-08-16T08:09:02.014Z,1281946142.014 [BuoyancyServo](INFO): Powering down 2010-08-16T08:09:02.026Z,1281946142.026 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2010-08-16T08:09:02.026Z,1281946142.026 [ElevatorServo](INFO): Powering down 2010-08-16T08:09:02.027Z,1281946142.027 [MassServo](DEBUG): Uninitialize Mass Servo. 2010-08-16T08:09:02.027Z,1281946142.027 [MassServo](INFO): Powering down 2010-08-16T08:09:02.028Z,1281946142.028 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2010-08-16T08:09:02.028Z,1281946142.028 [RudderServo](INFO): Powering down 2010-08-16T08:09:02.029Z,1281946142.029 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2010-08-16T08:09:02.029Z,1281946142.029 [ThrusterHE](INFO): Powering down 2010-08-16T08:09:02.030Z,1281946142.030 [SBIT](DEBUG): Uninitialize SBIT Component. 2010-08-16T08:09:02.030Z,1281946142.030 [IBIT](DEBUG): Uninitialize IBIT Component. 2010-08-16T08:09:02.031Z,1281946142.031 [CBIT](DEBUG): Uninitialize CBIT Component. 2010-08-16T08:09:02.031Z,1281946142.031 [CBIT](DEBUG): Powering off loads. 2010-08-16T08:09:02.041Z,1281946142.041 [CBIT](DEBUG): Disabling WDT. 2010-08-16T08:09:02.053Z,1281946142.053 [CBIT](DEBUG): Opening all GF detection circuits. 2010-08-16T08:09:02.055Z,1281946142.055 [controlThread ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:02.095Z,1281946142.095 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:02.099Z,1281946142.099 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:02.144Z,1281946142.144 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:02.146Z,1281946142.146 [Onboard ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:02.152Z,1281946142.152 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:02.198Z,1281946142.198 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:02.237Z,1281946142.237 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2010-08-16T08:09:02.301Z,1281946142.301 [logger ThreadHandler](INFO): Thread cancelled.