2013-05-07T11:01:08.204Z,1367924468.204 [Supervisor](DEBUG): Initializing supervisor.
2013-05-07T11:01:08.207Z,1367924468.207 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2013-05-07T11:01:08.207Z,1367924468.207 [SyncHandler](INFO): Protected caller Thread ID is 2027
2013-05-07T11:01:08.208Z,1367924468.208 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-05-07T11:01:08.209Z,1367924468.209 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2013-05-07T11:01:08.209Z,1367924468.209 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2028
2013-05-07T11:01:08.212Z,1367924468.212 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-05-07T11:01:08.229Z,1367924468.229 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-05-07T11:01:08.230Z,1367924468.230 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2013-05-07T11:01:08.230Z,1367924468.230 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2029
2013-05-07T11:01:08.231Z,1367924468.231 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-05-07T11:01:08.231Z,1367924468.231 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2013-05-07T11:01:08.232Z,1367924468.232 [logger ThreadHandler](INFO): Protected caller Thread ID is 2030
2013-05-07T11:01:08.234Z,1367924468.234 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2013-05-07T11:01:08.234Z,1367924468.234 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-05-07T11:01:08.236Z,1367924468.236 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2013-05-07T11:01:08.327Z,1367924468.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2013-05-07T11:01:08.328Z,1367924468.328 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2013-05-07T11:01:08.516Z,1367924468.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2013-05-07T11:01:08.517Z,1367924468.517 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2013-05-07T11:01:08.711Z,1367924468.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2013-05-07T11:01:08.712Z,1367924468.712 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2013-05-07T11:01:08.845Z,1367924468.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2013-05-07T11:01:08.846Z,1367924468.846 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-05-07T11:01:09.424Z,1367924469.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-05-07T11:01:09.425Z,1367924469.425 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-05-07T11:01:09.879Z,1367924469.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-05-07T11:01:09.879Z,1367924469.879 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-05-07T11:01:10.372Z,1367924470.372 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-05-07T11:01:10.373Z,1367924470.373 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-05-07T11:01:10.658Z,1367924470.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-05-07T11:01:10.659Z,1367924470.659 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-05-07T11:01:10.962Z,1367924470.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-05-07T11:01:10.963Z,1367924470.963 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-05-07T11:01:11.364Z,1367924471.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-05-07T11:01:11.365Z,1367924471.365 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-05-07T11:01:11.577Z,1367924471.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-05-07T11:01:11.578Z,1367924471.578 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-05-07T11:01:11.683Z,1367924471.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-05-07T11:01:11.684Z,1367924471.684 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-05-07T11:01:11.762Z,1367924471.762 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-05-07T11:01:11.860Z,1367924471.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-05-07T11:01:11.861Z,1367924471.861 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-05-07T11:01:12.045Z,1367924472.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-05-07T11:01:12.046Z,1367924472.046 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-05-07T11:01:12.247Z,1367924472.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-05-07T11:01:12.249Z,1367924472.249 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2013-05-07T11:01:12.249Z,1367924472.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2013-05-07T11:01:12.330Z,1367924472.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2013-05-07T11:01:12.573Z,1367924472.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2013-05-07T11:01:12.574Z,1367924472.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2013-05-07T11:01:12.665Z,1367924472.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2013-05-07T11:01:12.822Z,1367924472.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2013-05-07T11:01:13.019Z,1367924473.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2013-05-07T11:01:13.101Z,1367924473.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2013-05-07T11:01:13.223Z,1367924473.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2013-05-07T11:01:13.402Z,1367924473.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2013-05-07T11:01:13.858Z,1367924473.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2013-05-07T11:01:13.961Z,1367924473.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2013-05-07T11:01:14.321Z,1367924474.321 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2013-05-07T11:01:14.321Z,1367924474.321 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-05-07T11:01:14.336Z,1367924474.336 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-05-07T11:01:14.432Z,1367924474.432 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-05-07T11:01:14.432Z,1367924474.432 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-05-07T11:01:14.542Z,1367924474.542 [BuoyancyServo] Loaded
2013-05-07T11:01:14.542Z,1367924474.542 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-05-07T11:01:14.559Z,1367924474.559 [ElevatorServo] Loaded
2013-05-07T11:01:14.559Z,1367924474.559 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-05-07T11:01:14.575Z,1367924474.575 [MassServo] Loaded
2013-05-07T11:01:14.576Z,1367924474.576 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-05-07T11:01:14.592Z,1367924474.592 [RudderServo] Loaded
2013-05-07T11:01:14.592Z,1367924474.592 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-05-07T11:01:14.608Z,1367924474.608 [ThrusterServo] Loaded
2013-05-07T11:01:14.609Z,1367924474.609 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-05-07T11:01:14.609Z,1367924474.609 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-05-07T11:01:14.609Z,1367924474.609 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-05-07T11:01:14.742Z,1367924474.742 [SBIT](DEBUG): Construct Startup Built In Test.
2013-05-07T11:01:14.756Z,1367924474.756 [SBIT] Loaded
2013-05-07T11:01:14.756Z,1367924474.756 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-05-07T11:01:14.757Z,1367924474.757 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-05-07T11:01:14.776Z,1367924474.776 [IBIT] Loaded
2013-05-07T11:01:14.776Z,1367924474.776 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-05-07T11:01:14.779Z,1367924474.779 [CBIT](DEBUG): Construct Continuous Built In Test.
2013-05-07T11:01:14.975Z,1367924474.975 [CBIT] Loaded
2013-05-07T11:01:14.975Z,1367924474.975 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-05-07T11:01:14.975Z,1367924474.975 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-05-07T11:01:14.976Z,1367924474.976 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-05-07T11:01:15.164Z,1367924475.164 [CANONSampler] Loaded
2013-05-07T11:01:15.164Z,1367924475.164 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2013-05-07T11:01:15.182Z,1367924475.182 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2013-05-07T11:01:15.185Z,1367924475.185 [CTD_Seabird](INFO): created writer for : sea_water_density
2013-05-07T11:01:15.186Z,1367924475.186 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2013-05-07T11:01:15.189Z,1367924475.189 [CTD_Seabird](INFO): created writer for : depth
2013-05-07T11:01:15.189Z,1367924475.189 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2013-05-07T11:01:15.192Z,1367924475.192 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2013-05-07T11:01:15.193Z,1367924475.193 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2013-05-07T11:01:15.195Z,1367924475.195 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2013-05-07T11:01:15.196Z,1367924475.196 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2013-05-07T11:01:15.199Z,1367924475.199 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2013-05-07T11:01:15.200Z,1367924475.200 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2013-05-07T11:01:15.203Z,1367924475.203 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2013-05-07T11:01:15.203Z,1367924475.203 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2013-05-07T11:01:15.206Z,1367924475.206 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2013-05-07T11:01:15.206Z,1367924475.206 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2013-05-07T11:01:15.209Z,1367924475.209 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2013-05-07T11:01:15.210Z,1367924475.210 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2013-05-07T11:01:15.210Z,1367924475.210 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2013-05-07T11:01:15.225Z,1367924475.225 [CTD_Seabird] Loaded
2013-05-07T11:01:15.225Z,1367924475.225 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2013-05-07T11:01:15.226Z,1367924475.226 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0
2013-05-07T11:01:15.227Z,1367924475.227 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2113
2013-05-07T11:01:15.254Z,1367924475.254 [ESPComponent] Loaded
2013-05-07T11:01:15.255Z,1367924475.255 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2013-05-07T11:01:15.271Z,1367924475.271 [PAR_Licor] Loaded
2013-05-07T11:01:15.271Z,1367924475.271 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-05-07T11:01:15.283Z,1367924475.283 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2013-05-07T11:01:15.284Z,1367924475.284 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2013-05-07T11:01:15.285Z,1367924475.285 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2013-05-07T11:01:15.286Z,1367924475.286 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2013-05-07T11:01:15.287Z,1367924475.287 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2013-05-07T11:01:15.288Z,1367924475.288 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2013-05-07T11:01:15.290Z,1367924475.290 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2013-05-07T11:01:15.290Z,1367924475.290 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2013-05-07T11:01:15.291Z,1367924475.291 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2013-05-07T11:01:15.292Z,1367924475.292 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2013-05-07T11:01:15.294Z,1367924475.294 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2013-05-07T11:01:15.294Z,1367924475.294 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2013-05-07T11:01:15.296Z,1367924475.296 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2013-05-07T11:01:15.296Z,1367924475.296 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2013-05-07T11:01:15.298Z,1367924475.298 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2013-05-07T11:01:15.300Z,1367924475.300 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2013-05-07T11:01:15.300Z,1367924475.300 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2013-05-07T11:01:15.301Z,1367924475.301 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2013-05-07T11:01:15.302Z,1367924475.302 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2013-05-07T11:01:15.303Z,1367924475.303 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2013-05-07T11:01:15.305Z,1367924475.305 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2013-05-07T11:01:15.305Z,1367924475.305 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2013-05-07T11:01:15.307Z,1367924475.307 [WetLabsBB2FL] Loaded
2013-05-07T11:01:15.307Z,1367924475.307 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-05-07T11:01:15.308Z,1367924475.308 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0
2013-05-07T11:01:15.308Z,1367924475.308 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2114
2013-05-07T11:01:15.309Z,1367924475.309 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-05-07T11:01:15.309Z,1367924475.309 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-05-07T11:01:15.345Z,1367924475.345 [DepthRateCalculator] Loaded
2013-05-07T11:01:15.346Z,1367924475.346 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-05-07T11:01:15.349Z,1367924475.349 [PitchRateCalculator] Loaded
2013-05-07T11:01:15.349Z,1367924475.349 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-05-07T11:01:15.357Z,1367924475.357 [SpeedCalculator] Loaded
2013-05-07T11:01:15.357Z,1367924475.357 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-05-07T11:01:15.368Z,1367924475.368 [TempGradientCalculator] Loaded
2013-05-07T11:01:15.368Z,1367924475.368 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-05-07T11:01:15.371Z,1367924475.371 [YawRateCalculator] Loaded
2013-05-07T11:01:15.371Z,1367924475.371 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-05-07T11:01:15.387Z,1367924475.387 [ElevatorOffsetCalculator] Loaded
2013-05-07T11:01:15.387Z,1367924475.387 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2013-05-07T11:01:15.387Z,1367924475.387 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-05-07T11:01:15.388Z,1367924475.388 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2013-05-07T11:01:15.462Z,1367924475.462 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2013-05-07T11:01:15.463Z,1367924475.463 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-05-07T11:01:15.488Z,1367924475.488 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-05-07T11:01:15.489Z,1367924475.489 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2013-05-07T11:01:15.565Z,1367924475.565 [DeadReckonUsingMultipleVelocitySources] Loaded
2013-05-07T11:01:15.565Z,1367924475.565 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2013-05-07T11:01:15.576Z,1367924475.576 [NavChart] Loaded
2013-05-07T11:01:15.576Z,1367924475.576 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-05-07T11:01:15.580Z,1367924475.580 [UniversalFixResidualReporter] Loaded
2013-05-07T11:01:15.580Z,1367924475.580 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2013-05-07T11:01:15.581Z,1367924475.581 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2013-05-07T11:01:15.581Z,1367924475.581 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-05-07T11:01:15.928Z,1367924475.928 [DataOverHttps] Loaded
2013-05-07T11:01:15.928Z,1367924475.928 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2013-05-07T11:01:15.929Z,1367924475.929 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409CC4E0
2013-05-07T11:01:15.929Z,1367924475.929 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2115
2013-05-07T11:01:15.946Z,1367924475.946 [Depth_Keller] Loaded
2013-05-07T11:01:15.946Z,1367924475.946 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-05-07T11:01:15.948Z,1367924475.948 [DropWeight] Loaded
2013-05-07T11:01:15.949Z,1367924475.949 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-05-07T11:01:16.000Z,1367924476.000 [NAL9602] Loaded
2013-05-07T11:01:16.000Z,1367924476.000 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-05-07T11:01:16.022Z,1367924476.022 [Onboard] Loaded
2013-05-07T11:01:16.022Z,1367924476.022 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2013-05-07T11:01:16.023Z,1367924476.023 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409FC4E0
2013-05-07T11:01:16.023Z,1367924476.023 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2116
2013-05-07T11:01:16.034Z,1367924476.034 [Radio_Surface] Loaded
2013-05-07T11:01:16.034Z,1367924476.034 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2013-05-07T11:01:16.035Z,1367924476.035 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A2C4E0
2013-05-07T11:01:16.035Z,1367924476.035 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2117
2013-05-07T11:01:16.108Z,1367924476.108 [DAT] Loaded
2013-05-07T11:01:16.108Z,1367924476.108 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-05-07T11:01:16.803Z,1367924476.803 [BPC1] Loaded
2013-05-07T11:01:16.803Z,1367924476.803 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2013-05-07T11:01:16.821Z,1367924476.821 [AMEcho] Loaded
2013-05-07T11:01:16.821Z,1367924476.821 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2013-05-07T11:01:16.822Z,1367924476.822 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-05-07T11:01:16.822Z,1367924476.822 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2013-05-07T11:01:16.905Z,1367924476.905 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2013-05-07T11:01:16.906Z,1367924476.906 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-05-07T11:01:16.952Z,1367924476.952 [VerticalControl](DEBUG): Construct VerticalControl.
2013-05-07T11:01:17.000Z,1367924477.000 [VerticalControl] Loaded
2013-05-07T11:01:17.000Z,1367924477.000 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-05-07T11:01:17.001Z,1367924477.001 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-05-07T11:01:17.035Z,1367924477.035 [HorizontalControl] Loaded
2013-05-07T11:01:17.035Z,1367924477.035 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-05-07T11:01:17.036Z,1367924477.036 [SpeedControl](DEBUG): Construct SpeedControl.
2013-05-07T11:01:17.037Z,1367924477.037 [SpeedControl] Loaded
2013-05-07T11:01:17.038Z,1367924477.038 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-05-07T11:01:17.038Z,1367924477.038 [LoopControl](DEBUG): Construct LoopControl.
2013-05-07T11:01:17.039Z,1367924477.039 [LoopControl] Loaded
2013-05-07T11:01:17.039Z,1367924477.039 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-05-07T11:01:17.040Z,1367924477.040 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-05-07T11:01:17.041Z,1367924477.041 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-05-07T11:01:17.059Z,1367924477.059 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-05-07T11:01:17.059Z,1367924477.059 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-05-07T11:01:17.337Z,1367924477.337 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-05-07T11:01:17.341Z,1367924477.341 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-05-07T11:01:17.342Z,1367924477.342 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-05-07T11:01:17.350Z,1367924477.350 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-05-07T11:01:17.351Z,1367924477.351 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0
2013-05-07T11:01:17.352Z,1367924477.352 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2118
2013-05-07T11:01:17.356Z,1367924477.356 [Supervisor](INFO): Main Thread ID is 2026
2013-05-07T11:01:17.357Z,1367924477.357 [Supervisor](DEBUG): Running supervisor.
2013-05-07T11:01:17.357Z,1367924477.357 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2119
2013-05-07T11:01:17.359Z,1367924477.359 [controlThread ThreadHandler](INFO): Handler Thread ID is 2120
2013-05-07T11:01:17.360Z,1367924477.360 [controlThread](DEBUG): Initializing ControlThread
2013-05-07T11:01:17.362Z,1367924477.362 [SBIT](INFO): Initialize SBIT Component.
2013-05-07T11:01:17.362Z,1367924477.362 [SBIT](IMPORTANT): git: 2020-10-07_A
2013-05-07T11:01:17.362Z,1367924477.362 [SBIT](INFO): git hash: c52f40a9277e8d17c6912ac1d266a8bed1742f02
2013-05-07T11:01:17.363Z,1367924477.363 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2013-05-07T11:01:17.364Z,1367924477.364 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Fri Mar 5 08:17:40 PST 2021
2013-05-07T11:01:17.365Z,1367924477.365 [SBIT](INFO): Beginning SBIT in 76.000000 seconds.
2013-05-07T11:01:17.366Z,1367924477.366 [IBIT](INFO): Initialize IBIT Component.
2013-05-07T11:01:17.366Z,1367924477.366 [CBIT](DEBUG): Initialize CBIT Component.
2013-05-07T11:01:17.367Z,1367924477.367 [logger ThreadHandler](INFO): Handler Thread ID is 2121
2013-05-07T11:01:17.380Z,1367924477.380 [CBIT](DEBUG): Initialized mux pins.
2013-05-07T11:01:17.380Z,1367924477.380 [CBIT](DEBUG): Initializing the watchdog timer.
2013-05-07T11:01:17.388Z,1367924477.388 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2122
2013-05-07T11:01:17.389Z,1367924477.389 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:01:17.393Z,1367924477.393 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2124
2013-05-07T11:01:17.394Z,1367924477.394 [WetLabsBB2FL](INFO): Powering down
2013-05-07T11:01:17.404Z,1367924477.404 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-05-07T11:01:17.404Z,1367924477.404 [CBIT](DEBUG): Initializing heartbeat.
2013-05-07T11:01:17.428Z,1367924477.428 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2125
2013-05-07T11:01:17.429Z,1367924477.429 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2013-05-07T11:01:17.440Z,1367924477.440 [Onboard ThreadHandler](INFO): Handler Thread ID is 2126
2013-05-07T11:01:17.458Z,1367924477.458 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2127
2013-05-07T11:01:17.476Z,1367924477.476 [CBIT](DEBUG): Deactivating GF circuits.
2013-05-07T11:01:17.476Z,1367924477.476 [CBIT](DEBUG): Deactivating emergency mode.
2013-05-07T11:01:17.477Z,1367924477.477 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2128
2013-05-07T11:01:17.479Z,1367924477.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2013-05-07T11:01:17.480Z,1367924477.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-05-07T11:01:17.480Z,1367924477.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2013-05-07T11:01:17.480Z,1367924477.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-05-07T11:01:17.480Z,1367924477.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2013-05-07T11:01:17.480Z,1367924477.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-05-07T11:01:17.480Z,1367924477.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2013-05-07T11:01:17.481Z,1367924477.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-05-07T11:01:17.481Z,1367924477.481 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2013-05-07T11:01:17.481Z,1367924477.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-05-07T11:01:17.481Z,1367924477.481 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2013-05-07T11:01:17.481Z,1367924477.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-05-07T11:01:17.481Z,1367924477.481 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2013-05-07T11:01:17.481Z,1367924477.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-05-07T11:01:17.482Z,1367924477.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2013-05-07T11:01:17.482Z,1367924477.482 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-05-07T11:01:17.512Z,1367924477.512 [CBIT](DEBUG): Backplane powered.
2013-05-07T11:01:17.516Z,1367924477.516 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-05-07T11:01:17.517Z,1367924477.517 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-05-07T11:01:17.517Z,1367924477.517 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-05-07T11:01:17.517Z,1367924477.517 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-05-07T11:01:17.518Z,1367924477.518 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-05-07T11:01:17.518Z,1367924477.518 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2013-05-07T11:01:17.519Z,1367924477.519 [NavChart](DEBUG): Initialize NavChart Navigation.
2013-05-07T11:01:17.520Z,1367924477.520 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2013-05-07T11:01:17.526Z,1367924477.526 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-05-07T11:01:17.528Z,1367924477.528 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-05-07T11:01:17.529Z,1367924477.529 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-05-07T11:01:17.529Z,1367924477.529 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-05-07T11:01:17.530Z,1367924477.530 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-05-07T11:01:17.564Z,1367924477.564 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-05-07T11:01:17.591Z,1367924477.591 [MissionManager](DEBUG):
2013-05-07T11:01:17.591Z,1367924477.591 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-05-07T11:01:17.683Z,1367924477.683 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2013-05-07T11:01:17.684Z,1367924477.684 [Default:A.Wait](DEBUG): Construct Wait.
2013-05-07T11:01:17.686Z,1367924477.686 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-05-07T11:01:17.740Z,1367924477.740 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-05-07T11:01:17.743Z,1367924477.743 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2013-05-07T11:01:17.763Z,1367924477.763 [Default:E.Execute](DEBUG): Construct Execute.
2013-05-07T11:01:17.766Z,1367924477.766 [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
2013-05-07T11:01:17.787Z,1367924477.787 [controlThread](DEBUG): Component order: CycleStarter,CANONSampler,ESPComponent,PAR_Licor,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,AMEcho,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2013-05-07T11:01:17.792Z,1367924477.792 [CANONSampler](INFO): Powering down
2013-05-07T11:01:17.803Z,1367924477.803 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2013-05-07T11:01:17.834Z,1367924477.834 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2013-05-07T11:01:17.834Z,1367924477.834 [DAT](INFO): Powering up
2013-05-07T11:01:17.835Z,1367924477.835 [DAT](DEBUG): Initializing DAT.
2013-05-07T11:01:17.856Z,1367924477.856 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2013-05-07T11:01:17.897Z,1367924477.897 [Radio_Surface](INFO): Powering up
2013-05-07T11:01:17.897Z,1367924477.897 [AMEcho](INFO): Powering up
2013-05-07T11:01:17.899Z,1367924477.899 [AMEcho](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2013-05-07T11:01:17.924Z,1367924477.924 [DepthRateCalculator](ERROR): Depth measurement is not active
2013-05-07T11:01:17.955Z,1367924477.955 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:17.988Z,1367924477.988 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:17.989Z,1367924477.989 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:17.996Z,1367924477.996 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-05-07T11:01:17.997Z,1367924477.997 [MassServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:18.012Z,1367924478.012 [MassServo](DEBUG): Initializing MassServo.
2013-05-07T11:01:18.013Z,1367924478.013 [RudderServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:18.020Z,1367924478.020 [RudderServo](DEBUG): Initializing RudderServo.
2013-05-07T11:01:18.021Z,1367924478.021 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:18.028Z,1367924478.028 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-05-07T11:01:18.310Z,1367924478.310 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2013-05-07T11:01:18.310Z,1367924478.310 [DropWeight] Hardware Fault, FailCount= 1
2013-05-07T11:01:18.310Z,1367924478.310 [DropWeight](ERROR): Hardware Fault
2013-05-07T11:01:18.484Z,1367924478.484 [AMEcho](INFO): Powering down
2013-05-07T11:01:18.502Z,1367924478.502 [CommandLine](FAULT): Scheduling is paused
2013-05-07T11:01:18.503Z,1367924478.503 [CBIT](INFO): Critical error at 20130507T110118
2013-05-07T11:01:18.503Z,1367924478.503 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2013-05-07T11:01:18.554Z,1367924478.554 [CBIT](ERROR): Hardware Fault in component: DropWeight
2013-05-07T11:01:18.554Z,1367924478.554 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2013-05-07T11:01:18.920Z,1367924478.920 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2013-05-07T11:01:18.920Z,1367924478.920 [RudderServo](FAULT): Rudder failed to initialize
2013-05-07T11:01:18.920Z,1367924478.920 [RudderServo] Communications Fault, FailCount= 1
2013-05-07T11:01:18.920Z,1367924478.920 [RudderServo](ERROR): Communications Fault
2013-05-07T11:01:19.029Z,1367924479.029 [CBIT](INFO): Critical error at 20130507T110118
2013-05-07T11:01:19.031Z,1367924479.031 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-05-07T11:01:19.054Z,1367924479.054 [AMEcho](IMPORTANT): Setting 'enabled' to 0
2013-05-07T11:01:19.400Z,1367924479.400 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:19.400Z,1367924479.400 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:19.400Z,1367924479.400 [BuoyancyServo] Communications Fault, FailCount= 1
2013-05-07T11:01:19.400Z,1367924479.400 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:19.449Z,1367924479.449 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-05-07T11:01:19.449Z,1367924479.449 [RudderServo](INFO): Powering down
2013-05-07T11:01:19.455Z,1367924479.455 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:19.915Z,1367924479.915 [RudderServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:20.033Z,1367924480.033 [RudderServo](DEBUG): Initializing RudderServo.
2013-05-07T11:01:20.037Z,1367924480.037 [CBIT](INFO): Clearing failed state for component RudderServo
2013-05-07T11:01:20.037Z,1367924480.037 [RudderServo] No Fault, FailCount= 1
2013-05-07T11:01:21.655Z,1367924481.655 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:21.655Z,1367924481.655 [BuoyancyServo] No Fault, FailCount= 1
2013-05-07T11:01:21.945Z,1367924481.945 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:22.065Z,1367924482.065 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:23.480Z,1367924483.480 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:23.480Z,1367924483.480 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:23.480Z,1367924483.480 [BuoyancyServo] Communications Fault, FailCount= 2
2013-05-07T11:01:23.480Z,1367924483.480 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:23.580Z,1367924483.580 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:25.684Z,1367924485.684 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:25.684Z,1367924485.684 [BuoyancyServo] No Fault, FailCount= 2
2013-05-07T11:01:26.062Z,1367924486.062 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:26.181Z,1367924486.181 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:27.596Z,1367924487.596 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:27.596Z,1367924487.596 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:27.596Z,1367924487.596 [BuoyancyServo] Communications Fault, FailCount= 3
2013-05-07T11:01:27.596Z,1367924487.596 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:27.636Z,1367924487.636 [CBIT](CRITICAL): Environmental Failure. Press:14.679048 PSI. Humidity:38%. Temp:20 C. ABORTING MISSION
2013-05-07T11:01:27.636Z,1367924487.636 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:27.794Z,1367924487.794 [CBIT](INFO): Critical error at 20130507T110127
2013-05-07T11:01:29.752Z,1367924489.752 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:29.752Z,1367924489.752 [BuoyancyServo] No Fault, FailCount= 3
2013-05-07T11:01:30.087Z,1367924490.087 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:30.205Z,1367924490.205 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:30.440Z,1367924490.440 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:01:30.440Z,1367924490.440 [CTD_Seabird] Communications Fault, FailCount= 1
2013-05-07T11:01:30.440Z,1367924490.440 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:01:30.517Z,1367924490.517 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:01:30.844Z,1367924490.844 [CTD_Seabird](INFO): Powering down
2013-05-07T11:01:30.983Z,1367924490.983 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005026
2013-05-07T11:01:31.624Z,1367924491.624 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:31.624Z,1367924491.624 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:31.624Z,1367924491.624 [BuoyancyServo] Communications Fault, FailCount= 4
2013-05-07T11:01:31.624Z,1367924491.624 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:31.656Z,1367924491.656 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:31.691Z,1367924491.691 [DAT](INFO): commRate: 800
2013-05-07T11:01:31.784Z,1367924491.784 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:01:31.784Z,1367924491.784 [CTD_Seabird] No Fault, FailCount= 1
2013-05-07T11:01:32.184Z,1367924492.184 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:01:33.709Z,1367924493.709 [DAT](INFO): entering command mode
2013-05-07T11:01:33.757Z,1367924493.757 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:33.757Z,1367924493.757 [BuoyancyServo] No Fault, FailCount= 4
2013-05-07T11:01:34.113Z,1367924494.113 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:34.123Z,1367924494.123 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:34.241Z,1367924494.241 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:34.517Z,1367924494.517 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:34.921Z,1367924494.921 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:35.325Z,1367924495.325 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:35.684Z,1367924495.684 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:35.684Z,1367924495.684 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:35.684Z,1367924495.684 [BuoyancyServo] Communications Fault, FailCount= 5
2013-05-07T11:01:35.684Z,1367924495.684 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:35.724Z,1367924495.724 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:35.738Z,1367924495.738 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:36.141Z,1367924496.141 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:36.561Z,1367924496.561 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:36.969Z,1367924496.969 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:37.353Z,1367924497.353 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:37.757Z,1367924497.757 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:37.842Z,1367924497.842 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:37.842Z,1367924497.842 [BuoyancyServo] No Fault, FailCount= 5
2013-05-07T11:01:38.165Z,1367924498.165 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:38.191Z,1367924498.191 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:38.305Z,1367924498.305 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:38.569Z,1367924498.569 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:38.965Z,1367924498.965 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:39.373Z,1367924499.373 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:39.708Z,1367924499.708 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:39.708Z,1367924499.708 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:39.708Z,1367924499.708 [BuoyancyServo] Communications Fault, FailCount= 6
2013-05-07T11:01:39.708Z,1367924499.708 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:39.796Z,1367924499.796 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:39.810Z,1367924499.810 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:40.213Z,1367924500.213 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:40.617Z,1367924500.617 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:41.018Z,1367924501.018 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:41.425Z,1367924501.425 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:41.829Z,1367924501.829 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:41.884Z,1367924501.884 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:41.884Z,1367924501.884 [BuoyancyServo] No Fault, FailCount= 6
2013-05-07T11:01:42.233Z,1367924502.233 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:42.257Z,1367924502.257 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:42.374Z,1367924502.374 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:42.637Z,1367924502.637 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:43.057Z,1367924503.057 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:43.445Z,1367924503.445 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:43.804Z,1367924503.804 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:43.804Z,1367924503.804 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:43.804Z,1367924503.804 [BuoyancyServo] Communications Fault, FailCount= 7
2013-05-07T11:01:43.804Z,1367924503.804 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:43.848Z,1367924503.848 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:43.868Z,1367924503.868 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:44.273Z,1367924504.273 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:44.677Z,1367924504.677 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:45.081Z,1367924505.081 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:45.236Z,1367924505.236 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:01:45.236Z,1367924505.236 [CTD_Seabird] Communications Fault, FailCount= 2
2013-05-07T11:01:45.236Z,1367924505.236 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:01:45.489Z,1367924505.489 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:45.560Z,1367924505.560 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:01:45.640Z,1367924505.640 [CTD_Seabird](INFO): Powering down
2013-05-07T11:01:45.889Z,1367924505.889 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:45.962Z,1367924505.962 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:45.963Z,1367924505.963 [BuoyancyServo] No Fault, FailCount= 7
2013-05-07T11:01:46.293Z,1367924506.293 [NAL9602](INFO): Powering up NAL9602
2013-05-07T11:01:46.294Z,1367924506.294 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:46.346Z,1367924506.346 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:46.465Z,1367924506.465 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:46.697Z,1367924506.697 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:46.754Z,1367924506.754 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:01:46.754Z,1367924506.754 [CTD_Seabird] No Fault, FailCount= 2
2013-05-07T11:01:46.984Z,1367924506.984 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:01:47.101Z,1367924507.101 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:47.505Z,1367924507.505 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:47.848Z,1367924507.848 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:47.848Z,1367924507.848 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:47.848Z,1367924507.848 [BuoyancyServo] Communications Fault, FailCount= 8
2013-05-07T11:01:47.848Z,1367924507.848 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:47.900Z,1367924507.900 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:47.915Z,1367924507.915 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:48.317Z,1367924508.317 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:48.721Z,1367924508.721 [DAT](DEBUG): checking for command mode acknowledgment
2013-05-07T11:01:48.721Z,1367924508.721 [DAT](FAULT): failed to enter command mode
2013-05-07T11:01:49.129Z,1367924509.129 [DAT](INFO): entering command mode
2013-05-07T11:01:49.530Z,1367924509.530 [DAT](INFO): setting verbose to 3
2013-05-07T11:01:49.934Z,1367924509.934 [DAT](INFO): set verbose to 3
2013-05-07T11:01:49.935Z,1367924509.935 [DAT](INFO): setting DatVerbose to 27440
2013-05-07T11:01:50.020Z,1367924510.020 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:50.020Z,1367924510.020 [BuoyancyServo] No Fault, FailCount= 8
2013-05-07T11:01:50.350Z,1367924510.350 [DAT](INFO): set DatVerbose to 27440
2013-05-07T11:01:50.351Z,1367924510.351 [DAT](INFO): setting transmit power to 8
2013-05-07T11:01:50.440Z,1367924510.440 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:50.561Z,1367924510.561 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:50.742Z,1367924510.742 [DAT](INFO): set transmit power to 8
2013-05-07T11:01:50.742Z,1367924510.742 [DAT](INFO): setting local address to 4
2013-05-07T11:01:51.146Z,1367924511.146 [DAT](INFO): set local address to 4
2013-05-07T11:01:51.888Z,1367924511.888 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:51.888Z,1367924511.888 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:51.888Z,1367924511.888 [BuoyancyServo] Communications Fault, FailCount= 9
2013-05-07T11:01:51.888Z,1367924511.888 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:51.920Z,1367924511.920 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:54.027Z,1367924514.027 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-05-07T11:01:54.027Z,1367924514.027 [BuoyancyServo] No Fault, FailCount= 9
2013-05-07T11:01:54.403Z,1367924514.403 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:01:54.517Z,1367924514.517 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:01:55.924Z,1367924515.924 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-05-07T11:01:55.924Z,1367924515.924 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-05-07T11:01:55.924Z,1367924515.924 [BuoyancyServo] Communications Fault, FailCount= 10
2013-05-07T11:01:55.924Z,1367924515.924 [BuoyancyServo](ERROR): Communications Fault
2013-05-07T11:01:55.960Z,1367924515.960 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:55.960Z,1367924515.960 [CBIT](CRITICAL): Communications Fault in component: BuoyancyServo
2013-05-07T11:01:56.107Z,1367924516.107 [CBIT](INFO): Critical error at 20130507T110155
2013-05-07T11:01:57.205Z,1367924517.205 [NAL9602](INFO): NAL9602 initialized
2013-05-07T11:02:00.044Z,1367924520.044 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:02:00.044Z,1367924520.044 [CTD_Seabird] Communications Fault, FailCount= 3
2013-05-07T11:02:00.044Z,1367924520.044 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:02:00.056Z,1367924520.056 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:02:00.057Z,1367924520.057 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2013-05-07T11:02:00.448Z,1367924520.448 [CTD_Seabird](INFO): Powering down
2013-05-07T11:02:24.303Z,1367924544.303 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2013-05-07T11:02:34.002Z,1367924554.002 [SBIT](IMPORTANT): Beginning Startup BIT
2013-05-07T11:02:34.006Z,1367924554.006 [CBIT](IMPORTANT): Beginning ground fault scan
2013-05-07T11:02:35.604Z,1367924555.604 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2013-05-07T11:02:39.244Z,1367924559.244 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2013-05-07T11:02:41.798Z,1367924561.798 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 1010.000000 cubic_centimeter
2013-05-07T11:02:41.799Z,1367924561.799 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2013-05-07T11:02:42.217Z,1367924562.217 [controlThread](DEBUG): Component order: CycleStarter,CANONSampler,ESPComponent,PAR_Licor,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,AMEcho,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2013-05-07T11:02:44.944Z,1367924564.944 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001272
CHAN A1 (24V): -0.000579
CHAN A2 (12V): -0.005382
CHAN A3 (5V): -0.002652
CHAN B0 (3.3V): -0.001228
CHAN B1 (3.15aV): -0.001615
CHAN B2 (3.15bV): -0.001013
CHAN B3 (GND): 0.000203
OPEN: -0.000676
Full Scale Calc: 4.765 mA, -1.589 mA
2013-05-07T11:02:46.513Z,1367924566.513 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2013-05-07T11:02:57.837Z,1367924577.837 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2013-05-07T11:03:09.149Z,1367924589.149 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2013-05-07T11:03:17.278Z,1367924597.278 [CommandLine](IMPORTANT): got command failComponent
2013-05-07T11:03:17.278Z,1367924597.278 [CommandLine](IMPORTANT): Failed components:
2013-05-07T11:03:17.279Z,1367924597.279 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2013-05-07T11:03:17.279Z,1367924597.279 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2013-05-07T11:03:17.279Z,1367924597.279 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2013-05-07T11:03:17.634Z,1367924597.634 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2013-05-07T11:03:17.634Z,1367924597.634 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2013-05-07T11:03:17.634Z,1367924597.634 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2013-05-07T11:03:17.725Z,1367924597.725 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2013-05-07T11:03:18.124Z,1367924598.124 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2013-05-07T11:03:18.124Z,1367924598.124 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2013-05-07T11:03:20.853Z,1367924600.853 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2013-05-07T11:03:26.070Z,1367924606.070 [CommandLine](IMPORTANT): got command failComponent none BuoyancyServo
2013-05-07T11:03:26.070Z,1367924606.070 [BuoyancyServo] No Fault, FailCount= 10
2013-05-07T11:03:26.070Z,1367924606.070 [CommandLine](IMPORTANT): BuoyancyServo failureMode is No Fault
2013-05-07T11:03:26.130Z,1367924606.130 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-07T11:03:26.249Z,1367924606.249 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-07T11:03:27.748Z,1367924607.748 [SBIT](IMPORTANT): SBIT PASSED
2013-05-07T11:03:27.749Z,1367924607.749 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2013-05-07T11:03:27.749Z,1367924607.749 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;
2013-05-07T11:03:27.750Z,1367924607.750 [SBIT](IMPORTANT): AMEcho.enabled=0 bool;
2013-05-07T11:03:27.750Z,1367924607.750 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;
2013-05-07T11:03:27.750Z,1367924607.750 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;
2013-05-07T11:03:27.750Z,1367924607.750 [SBIT](IMPORTANT): BuoyancyServo.checkingTimeout=15 minute;
2013-05-07T11:03:27.750Z,1367924607.750 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2013-05-07T11:03:27.750Z,1367924607.750 [SBIT](IMPORTANT): CANONSampler.rotateOnly=0 bool;
2013-05-07T11:03:27.750Z,1367924607.750 [SBIT](IMPORTANT): CANONSampler.simulateHardware=0 bool;
2013-05-07T11:03:27.750Z,1367924607.750 [SBIT](IMPORTANT): CBIT.gf24Offset=148.5 microampere;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=25 minute;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=280 cubic_centimeter;
2013-05-07T11:03:27.751Z,1367924607.751 [SBIT](IMPORTANT): VerticalControl.massDefault=14 millimeter;
2013-05-07T11:03:28.142Z,1367924608.142 [MissionManager](IMPORTANT): Started mission Startup
2013-05-07T11:03:28.143Z,1367924608.143 [Startup] Running Loop=1
2013-05-07T11:03:28.143Z,1367924608.143 [Startup](DEBUG): Aggregate::initialize Startup
2013-05-07T11:03:28.143Z,1367924608.143 [Startup:A.GoToSurface] Running Loop=1
2013-05-07T11:03:28.143Z,1367924608.143 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-07T11:03:28.144Z,1367924608.144 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2013-05-07T11:03:28.145Z,1367924608.145 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2013-05-07T11:03:28.145Z,1367924608.145 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2013-05-07T11:03:28.146Z,1367924608.146 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2013-05-07T11:03:28.146Z,1367924608.146 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2013-05-07T11:03:28.147Z,1367924608.147 [Startup:StartupSatComms] Running Loop=1
2013-05-07T11:03:28.147Z,1367924608.147 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2013-05-07T11:03:28.147Z,1367924608.147 [Startup:StartupSatComms:A] Running Loop=1
2013-05-07T11:03:28.545Z,1367924608.545 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-05-07T11:03:32.649Z,1367924612.649 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2013-05-07T11:03:44.285Z,1367924624.285 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2013-05-07T11:03:49.534Z,1367924629.534 [CommandLine](IMPORTANT): got command maintain clear
2013-05-07T11:03:49.637Z,1367924629.637 [controlThread](DEBUG): Component order: CycleStarter,CANONSampler,ESPComponent,PAR_Licor,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,AMEcho,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2013-05-07T11:04:22.581Z,1367924662.581 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 860.000000 cubic_centimeter
2013-05-07T11:04:22.582Z,1367924662.582 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2013-05-07T11:04:22.710Z,1367924662.710 [controlThread](DEBUG): Component order: CycleStarter,CANONSampler,ESPComponent,PAR_Licor,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,AMEcho,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2013-05-07T11:04:28.345Z,1367924668.345 [Startup:StartupSatComms:A](INFO): Timed out from 2013-05-07T11:03:28.1Z
2013-05-07T11:04:28.345Z,1367924668.345 [Startup:StartupSatComms:A] Stopped
2013-05-07T11:04:28.345Z,1367924668.345 [Startup:StartupSatComms:B] Running Loop=1
2013-05-07T11:04:28.741Z,1367924668.741 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-05-07T11:04:30.408Z,1367924670.408 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:04:30.408Z,1367924670.408 [CTD_Seabird] No Fault, FailCount= 3
2013-05-07T11:04:30.480Z,1367924670.480 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:04:34.774Z,1367924674.774 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2013-05-07T11:04:34.850Z,1367924674.850 [Reporter](INFO): platform_buoyancy_position 885.698425 cc
2013-05-07T11:04:35.237Z,1367924675.237 [Reporter](INFO): platform_buoyancy_position 881.266541 cc
2013-05-07T11:04:35.631Z,1367924675.631 [Reporter](INFO): platform_buoyancy_position 877.103271 cc
2013-05-07T11:04:35.763Z,1367924675.763 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20130505T113450/Courier0007.lzma
2013-05-07T11:04:36.044Z,1367924676.044 [Reporter](INFO): platform_buoyancy_position 870.522522 cc
2013-05-07T11:04:36.457Z,1367924676.457 [Reporter](INFO): platform_buoyancy_position 866.359253 cc
2013-05-07T11:04:36.766Z,1367924676.766 [DataOverHttps](INFO): Moved sent file to Logs/20130505T113450/Courier0007.lzma.bak
2013-05-07T11:04:36.766Z,1367924676.766 [DataOverHttps](INFO): SBD MOMSN=15394817
2013-05-07T11:04:43.544Z,1367924683.544 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:04:43.544Z,1367924683.544 [CTD_Seabird] Communications Fault, FailCount= 1
2013-05-07T11:04:43.544Z,1367924683.544 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:04:43.742Z,1367924683.742 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:04:43.948Z,1367924683.948 [CTD_Seabird](INFO): Powering down
2013-05-07T11:04:44.898Z,1367924684.898 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:04:44.898Z,1367924684.898 [CTD_Seabird] No Fault, FailCount= 1
2013-05-07T11:04:45.288Z,1367924685.288 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:04:49.462Z,1367924689.462 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 950.000000 cubic_centimeter
2013-05-07T11:04:53.468Z,1367924693.468 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20130507T110108/Courier0000.lzma
2013-05-07T11:04:54.470Z,1367924694.470 [DataOverHttps](INFO): Moved sent file to Logs/20130507T110108/Courier0000.lzma.bak
2013-05-07T11:04:54.470Z,1367924694.470 [DataOverHttps](INFO): SBD MOMSN=15394822
2013-05-07T11:04:55.074Z,1367924695.074 [Reporter](INFO): platform_buoyancy_position 862.061646 cc
2013-05-07T11:04:55.444Z,1367924695.444 [Reporter](INFO): platform_buoyancy_position 862.195923 cc
2013-05-07T11:04:55.862Z,1367924695.862 [Reporter](INFO): platform_buoyancy_position 862.061646 cc
2013-05-07T11:04:56.258Z,1367924696.258 [Reporter](INFO): platform_buoyancy_position 855.480957 cc
2013-05-07T11:04:56.658Z,1367924696.658 [Reporter](INFO): platform_buoyancy_position 857.629761 cc
2013-05-07T11:04:57.458Z,1367924697.458 [Reporter](INFO): platform_buoyancy_position 859.778564 cc
2013-05-07T11:04:57.874Z,1367924697.874 [Reporter](INFO): platform_buoyancy_position 860.047119 cc
2013-05-07T11:04:58.270Z,1367924698.270 [Reporter](INFO): platform_buoyancy_position 861.927368 cc
2013-05-07T11:04:58.340Z,1367924698.340 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:04:58.340Z,1367924698.340 [CTD_Seabird] Communications Fault, FailCount= 2
2013-05-07T11:04:58.340Z,1367924698.340 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:04:58.684Z,1367924698.684 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:04:58.686Z,1367924698.686 [Reporter](INFO): platform_buoyancy_position 863.941833 cc
2013-05-07T11:04:58.744Z,1367924698.744 [CTD_Seabird](INFO): Powering down
2013-05-07T11:04:59.088Z,1367924699.088 [Reporter](INFO): platform_buoyancy_position 864.076172 cc
2013-05-07T11:04:59.498Z,1367924699.498 [Reporter](INFO): platform_buoyancy_position 866.224915 cc
2013-05-07T11:04:59.896Z,1367924699.896 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:04:59.896Z,1367924699.896 [CTD_Seabird] No Fault, FailCount= 2
2013-05-07T11:04:59.898Z,1367924699.898 [Reporter](INFO): platform_buoyancy_position 866.359253 cc
2013-05-07T11:05:00.084Z,1367924700.084 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:05:00.314Z,1367924700.314 [Reporter](INFO): platform_buoyancy_position 868.508057 cc
2013-05-07T11:05:00.701Z,1367924700.701 [Reporter](INFO): platform_buoyancy_position 869.313843 cc
2013-05-07T11:05:01.114Z,1367924701.114 [Reporter](INFO): platform_buoyancy_position 870.656860 cc
2013-05-07T11:05:01.526Z,1367924701.526 [Reporter](INFO): platform_buoyancy_position 872.671326 cc
2013-05-07T11:05:01.930Z,1367924701.930 [Reporter](INFO): platform_buoyancy_position 873.208557 cc
2013-05-07T11:05:02.313Z,1367924702.313 [Reporter](INFO): platform_buoyancy_position 874.820129 cc
2013-05-07T11:05:02.727Z,1367924702.727 [Reporter](INFO): platform_buoyancy_position 876.968933 cc
2013-05-07T11:05:03.569Z,1367924703.569 [Reporter](INFO): platform_buoyancy_position 879.117737 cc
2013-05-07T11:05:03.958Z,1367924703.958 [Reporter](INFO): platform_buoyancy_position 880.595032 cc
2013-05-07T11:05:04.354Z,1367924704.354 [Reporter](INFO): platform_buoyancy_position 881.266541 cc
2013-05-07T11:05:04.754Z,1367924704.754 [Reporter](INFO): platform_buoyancy_position 883.549622 cc
2013-05-07T11:05:05.150Z,1367924705.150 [Reporter](INFO): platform_buoyancy_position 883.683960 cc
2013-05-07T11:05:05.536Z,1367924705.536 [Reporter](INFO): platform_buoyancy_position 885.698425 cc
2013-05-07T11:05:06.003Z,1367924706.003 [Reporter](INFO): platform_buoyancy_position 887.712952 cc
2013-05-07T11:05:06.366Z,1367924706.366 [Reporter](INFO): platform_buoyancy_position 887.847229 cc
2013-05-07T11:05:06.770Z,1367924706.770 [Reporter](INFO): platform_buoyancy_position 889.861755 cc
2013-05-07T11:05:07.155Z,1367924707.155 [Reporter](INFO): platform_buoyancy_position 890.130371 cc
2013-05-07T11:05:07.550Z,1367924707.550 [Reporter](INFO): platform_buoyancy_position 892.010559 cc
2013-05-07T11:05:07.962Z,1367924707.962 [Reporter](INFO): platform_buoyancy_position 894.159363 cc
2013-05-07T11:05:08.356Z,1367924708.356 [Reporter](INFO): platform_buoyancy_position 894.293640 cc
2013-05-07T11:05:08.781Z,1367924708.781 [Reporter](INFO): platform_buoyancy_position 896.308167 cc
2013-05-07T11:05:09.198Z,1367924709.198 [Reporter](INFO): platform_buoyancy_position 898.456970 cc
2013-05-07T11:05:09.594Z,1367924709.594 [Reporter](INFO): platform_buoyancy_position 898.591248 cc
2013-05-07T11:05:09.990Z,1367924709.990 [Reporter](INFO): platform_buoyancy_position 900.605774 cc
2013-05-07T11:05:10.422Z,1367924710.422 [Reporter](INFO): platform_buoyancy_position 900.740051 cc
2013-05-07T11:05:10.808Z,1367924710.808 [Reporter](INFO): platform_buoyancy_position 902.754517 cc
2013-05-07T11:05:11.206Z,1367924711.206 [Reporter](INFO): platform_buoyancy_position 904.903320 cc
2013-05-07T11:05:11.598Z,1367924711.598 [Reporter](INFO): platform_buoyancy_position 905.037659 cc
2013-05-07T11:05:12.006Z,1367924712.006 [Reporter](INFO): platform_buoyancy_position 907.052124 cc
2013-05-07T11:05:12.398Z,1367924712.398 [Reporter](INFO): platform_buoyancy_position 907.186462 cc
2013-05-07T11:05:12.597Z,1367924712.597 [DataOverHttps](INFO): Sending 997 bytes from file Logs/20130505T113450/Express0008.lzma
2013-05-07T11:05:12.810Z,1367924712.810 [Reporter](INFO): platform_buoyancy_position 909.335266 cc
2013-05-07T11:05:13.136Z,1367924713.136 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:05:13.136Z,1367924713.136 [CTD_Seabird] Communications Fault, FailCount= 3
2013-05-07T11:05:13.136Z,1367924713.136 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:05:13.213Z,1367924713.213 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:05:13.213Z,1367924713.213 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2013-05-07T11:05:13.215Z,1367924713.215 [Reporter](INFO): platform_buoyancy_position 911.215454 cc
2013-05-07T11:05:13.540Z,1367924713.540 [CTD_Seabird](INFO): Powering down
2013-05-07T11:05:13.598Z,1367924713.598 [DataOverHttps](INFO): Moved sent file to Logs/20130505T113450/Express0008.lzma.bak
2013-05-07T11:05:13.598Z,1367924713.598 [DataOverHttps](INFO): SBD MOMSN=15394829
2013-05-07T11:05:13.617Z,1367924713.617 [Reporter](INFO): platform_buoyancy_position 911.484070 cc
2013-05-07T11:05:14.028Z,1367924714.028 [Reporter](INFO): platform_buoyancy_position 913.498535 cc
2013-05-07T11:05:14.430Z,1367924714.430 [Reporter](INFO): platform_buoyancy_position 913.767151 cc
2013-05-07T11:05:14.862Z,1367924714.862 [Reporter](INFO): platform_buoyancy_position 915.647339 cc
2013-05-07T11:05:15.258Z,1367924715.258 [Reporter](INFO): platform_buoyancy_position 917.796143 cc
2013-05-07T11:05:15.647Z,1367924715.647 [Reporter](INFO): platform_buoyancy_position 918.064758 cc
2013-05-07T11:05:16.038Z,1367924716.038 [Reporter](INFO): platform_buoyancy_position 919.944946 cc
2013-05-07T11:05:16.454Z,1367924716.454 [Reporter](INFO): platform_buoyancy_position 922.093750 cc
2013-05-07T11:05:17.039Z,1367924717.039 [Reporter](INFO): platform_buoyancy_position 924.108276 cc
2013-05-07T11:05:17.262Z,1367924717.262 [Reporter](INFO): platform_buoyancy_position 924.376831 cc
2013-05-07T11:05:17.807Z,1367924717.807 [Reporter](INFO): platform_buoyancy_position 926.391357 cc
2013-05-07T11:05:18.070Z,1367924718.070 [Reporter](INFO): platform_buoyancy_position 926.525635 cc
2013-05-07T11:05:18.457Z,1367924718.457 [Reporter](INFO): platform_buoyancy_position 928.540161 cc
2013-05-07T11:05:18.889Z,1367924718.889 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2013-05-07T11:05:18.889Z,1367924718.889 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2013-05-07T11:05:18.889Z,1367924718.889 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2013-05-07T11:05:18.929Z,1367924718.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2013-05-07T11:05:18.930Z,1367924718.930 [Reporter](INFO): platform_buoyancy_position 928.808716 cc
2013-05-07T11:05:19.289Z,1367924719.289 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2013-05-07T11:05:19.289Z,1367924719.289 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2013-05-07T11:05:19.290Z,1367924719.290 [Reporter](INFO): platform_buoyancy_position 930.688965 cc
2013-05-07T11:05:19.702Z,1367924719.702 [Reporter](INFO): platform_buoyancy_position 932.837769 cc
2013-05-07T11:05:20.130Z,1367924720.130 [Reporter](INFO): platform_buoyancy_position 932.972046 cc
2013-05-07T11:05:20.524Z,1367924720.524 [Reporter](INFO): platform_buoyancy_position 934.852295 cc
2013-05-07T11:05:20.902Z,1367924720.902 [Reporter](INFO): platform_buoyancy_position 935.255127 cc
2013-05-07T11:05:21.318Z,1367924721.318 [Reporter](INFO): platform_buoyancy_position 937.135376 cc
2013-05-07T11:05:21.730Z,1367924721.730 [Reporter](INFO): platform_buoyancy_position 939.284180 cc
2013-05-07T11:05:22.146Z,1367924722.146 [Reporter](INFO): platform_buoyancy_position 939.552734 cc
2013-05-07T11:05:22.534Z,1367924722.534 [Reporter](INFO): platform_buoyancy_position 941.432983 cc
2013-05-07T11:05:28.580Z,1367924728.580 [Startup:StartupSatComms:B](INFO): Timed out from 2013-05-07T11:04:28.3Z
2013-05-07T11:05:28.580Z,1367924728.580 [Startup:StartupSatComms:B] Stopped
2013-05-07T11:05:28.581Z,1367924728.581 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-05-07T11:05:28.581Z,1367924728.581 [Startup:StartupSatComms] Stopped
2013-05-07T11:05:28.581Z,1367924728.581 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2013-05-07T11:05:28.581Z,1367924728.581 [Startup](INFO): Completed Startup
2013-05-07T11:05:28.582Z,1367924728.582 [MissionManager](INFO): Startup is completed.
2013-05-07T11:05:28.582Z,1367924728.582 [MissionManager](INFO): Uninitializing Mission Startup
2013-05-07T11:05:28.582Z,1367924728.582 [Startup] Stopped
2013-05-07T11:05:28.582Z,1367924728.582 [Startup](DEBUG): Aggregate::uninitialize Startup
2013-05-07T11:05:28.582Z,1367924728.582 [Startup:A.GoToSurface] Stopped
2013-05-07T11:05:28.582Z,1367924728.582 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-07T11:05:28.945Z,1367924728.945 [MissionManager](IMPORTANT): Started mission Default
2013-05-07T11:05:28.945Z,1367924728.945 [Default] Running Loop=1
2013-05-07T11:05:28.946Z,1367924728.946 [Default](DEBUG): Aggregate::initialize Default
2013-05-07T11:05:28.946Z,1367924728.946 [Default:B.GoToSurface] Running Loop=1
2013-05-07T11:05:28.946Z,1367924728.946 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-07T11:05:28.946Z,1367924728.946 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2013-05-07T11:05:28.946Z,1367924728.946 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2013-05-07T11:05:28.946Z,1367924728.946 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2013-05-07T11:05:28.947Z,1367924728.947 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2013-05-07T11:05:28.947Z,1367924728.947 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2013-05-07T11:05:28.947Z,1367924728.947 [Default:A.Wait] Running Loop=1
2013-05-07T11:05:28.947Z,1367924728.947 [Default:A.Wait](DEBUG): Initialize Wait Component.
2013-05-07T11:05:34.118Z,1367924734.118 [DataOverHttps](INFO): Sending 1570 bytes from file Logs/20130507T110108/Express0001.lzma
2013-05-07T11:05:34.734Z,1367924734.734 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 860.000000 cubic_centimeter
2013-05-07T11:05:35.117Z,1367924735.117 [DataOverHttps](INFO): Moved sent file to Logs/20130507T110108/Express0001.lzma.bak
2013-05-07T11:05:35.118Z,1367924735.118 [DataOverHttps](INFO): SBD MOMSN=15394970
2013-05-07T11:05:36.704Z,1367924736.704 [Reporter](INFO): platform_buoyancy_position 948.013672 cc
2013-05-07T11:05:37.470Z,1367924737.470 [Reporter](INFO): platform_buoyancy_position 945.730591 cc
2013-05-07T11:05:37.862Z,1367924737.862 [Reporter](INFO): platform_buoyancy_position 939.149902 cc
2013-05-07T11:05:38.286Z,1367924738.286 [Reporter](INFO): platform_buoyancy_position 937.135376 cc
2013-05-07T11:05:38.722Z,1367924738.722 [Reporter](INFO): platform_buoyancy_position 937.269653 cc
2013-05-07T11:05:39.106Z,1367924739.106 [Reporter](INFO): platform_buoyancy_position 937.135376 cc
2013-05-07T11:05:39.506Z,1367924739.506 [Reporter](INFO): platform_buoyancy_position 936.195190 cc
2013-05-07T11:05:39.895Z,1367924739.895 [Reporter](INFO): platform_buoyancy_position 935.120850 cc
2013-05-07T11:05:40.309Z,1367924740.309 [Reporter](INFO): platform_buoyancy_position 932.837769 cc
2013-05-07T11:05:41.135Z,1367924741.135 [Reporter](INFO): platform_buoyancy_position 930.957520 cc
2013-05-07T11:05:41.518Z,1367924741.518 [Reporter](INFO): platform_buoyancy_position 930.688965 cc
2013-05-07T11:05:41.921Z,1367924741.921 [Reporter](INFO): platform_buoyancy_position 928.674438 cc
2013-05-07T11:05:42.278Z,1367924742.278 [Default:A.Wait](INFO): Done Waiting.
2013-05-07T11:05:42.278Z,1367924742.278 [Default:A.Wait] Stopped
2013-05-07T11:05:42.278Z,1367924742.278 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2013-05-07T11:05:42.314Z,1367924742.314 [Reporter](INFO): platform_buoyancy_position 926.659912 cc
2013-05-07T11:05:42.699Z,1367924742.699 [Default:CheckIn] Running Loop=1
2013-05-07T11:05:42.699Z,1367924742.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2013-05-07T11:05:42.699Z,1367924742.699 [Default:CheckIn:Read_GPS] Running Loop=1
2013-05-07T11:05:42.734Z,1367924742.734 [Reporter](INFO): platform_buoyancy_position 926.525635 cc
2013-05-07T11:05:43.085Z,1367924743.085 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2013-05-07T11:05:43.121Z,1367924743.121 [Reporter](INFO): platform_buoyancy_position 926.391357 cc
2013-05-07T11:05:43.586Z,1367924743.586 [Reporter](INFO): platform_buoyancy_position 924.376831 cc
2013-05-07T11:05:43.934Z,1367924743.934 [Reporter](INFO): platform_buoyancy_position 922.093750 cc
2013-05-07T11:05:44.363Z,1367924744.363 [Reporter](INFO): platform_buoyancy_position 920.079224 cc
2013-05-07T11:05:45.250Z,1367924745.250 [Reporter](INFO): platform_buoyancy_position 917.796143 cc
2013-05-07T11:05:45.578Z,1367924745.578 [Reporter](INFO): platform_buoyancy_position 915.781677 cc
2013-05-07T11:05:46.378Z,1367924746.378 [Reporter](INFO): platform_buoyancy_position 913.632874 cc
2013-05-07T11:05:46.766Z,1367924746.766 [Reporter](INFO): platform_buoyancy_position 913.498535 cc
2013-05-07T11:05:47.179Z,1367924747.179 [Reporter](INFO): platform_buoyancy_position 911.349731 cc
2013-05-07T11:05:47.558Z,1367924747.558 [Reporter](INFO): platform_buoyancy_position 909.335266 cc
2013-05-07T11:05:48.380Z,1367924748.379 [Reporter](INFO): platform_buoyancy_position 907.052124 cc
2013-05-07T11:05:48.770Z,1367924748.770 [Reporter](INFO): platform_buoyancy_position 906.917847 cc
2013-05-07T11:05:49.190Z,1367924749.190 [Reporter](INFO): platform_buoyancy_position 905.037659 cc
2013-05-07T11:05:49.578Z,1367924749.578 [Reporter](INFO): platform_buoyancy_position 903.560364 cc
2013-05-07T11:05:49.986Z,1367924749.986 [Reporter](INFO): platform_buoyancy_position 902.888855 cc
2013-05-07T11:05:50.386Z,1367924750.386 [Reporter](INFO): platform_buoyancy_position 900.605774 cc
2013-05-07T11:05:50.798Z,1367924750.798 [Reporter](INFO): platform_buoyancy_position 900.202820 cc
2013-05-07T11:05:51.218Z,1367924751.218 [Reporter](INFO): platform_buoyancy_position 898.456970 cc
2013-05-07T11:05:51.634Z,1367924751.634 [Reporter](INFO): platform_buoyancy_position 896.442444 cc
2013-05-07T11:05:52.027Z,1367924752.027 [Reporter](INFO): platform_buoyancy_position 896.308167 cc
2013-05-07T11:05:52.451Z,1367924752.451 [Reporter](INFO): platform_buoyancy_position 894.159363 cc
2013-05-07T11:05:52.822Z,1367924752.822 [Reporter](INFO): platform_buoyancy_position 892.144836 cc
2013-05-07T11:05:53.683Z,1367924753.683 [Reporter](INFO): platform_buoyancy_position 889.996033 cc
2013-05-07T11:05:54.078Z,1367924754.078 [Reporter](INFO): platform_buoyancy_position 888.384460 cc
2013-05-07T11:05:54.474Z,1367924754.474 [Reporter](INFO): platform_buoyancy_position 887.847229 cc
2013-05-07T11:05:54.866Z,1367924754.866 [Reporter](INFO): platform_buoyancy_position 885.967041 cc
2013-05-07T11:05:55.253Z,1367924755.253 [Reporter](INFO): platform_buoyancy_position 885.564148 cc
2013-05-07T11:05:55.650Z,1367924755.650 [Reporter](INFO): platform_buoyancy_position 883.549622 cc
2013-05-07T11:05:56.076Z,1367924756.076 [Reporter](INFO): platform_buoyancy_position 881.400818 cc
2013-05-07T11:05:56.474Z,1367924756.474 [Reporter](INFO): platform_buoyancy_position 881.266541 cc
2013-05-07T11:05:57.034Z,1367924757.034 [Reporter](INFO): platform_buoyancy_position 879.117737 cc
2013-05-07T11:05:57.310Z,1367924757.310 [Reporter](INFO): platform_buoyancy_position 879.252075 cc
2013-05-07T11:05:57.739Z,1367924757.739 [Reporter](INFO): platform_buoyancy_position 876.968933 cc
2013-05-07T11:05:58.111Z,1367924758.111 [Reporter](INFO): platform_buoyancy_position 875.088745 cc
2013-05-07T11:05:58.630Z,1367924758.630 [Reporter](INFO): platform_buoyancy_position 874.685852 cc
2013-05-07T11:05:58.945Z,1367924758.945 [Reporter](INFO): platform_buoyancy_position 872.671326 cc
2013-05-07T11:05:59.334Z,1367924759.334 [Reporter](INFO): platform_buoyancy_position 872.805664 cc
2013-05-07T11:05:59.722Z,1367924759.722 [Reporter](INFO): platform_buoyancy_position 870.522522 cc
2013-05-07T11:06:00.090Z,1367924760.090 [Reporter](INFO): platform_buoyancy_position 868.508057 cc
2013-05-07T11:06:05.973Z,1367924765.973 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 950.000000 cubic_centimeter
2013-05-07T11:06:07.822Z,1367924767.822 [Reporter](INFO): platform_buoyancy_position 870.522522 cc
2013-05-07T11:06:08.578Z,1367924768.578 [Reporter](INFO): platform_buoyancy_position 870.388245 cc
2013-05-07T11:06:08.986Z,1367924768.986 [Reporter](INFO): platform_buoyancy_position 864.210449 cc
2013-05-07T11:06:09.435Z,1367924769.435 [Reporter](INFO): platform_buoyancy_position 866.224915 cc
2013-05-07T11:06:09.790Z,1367924769.790 [Reporter](INFO): platform_buoyancy_position 866.359253 cc
2013-05-07T11:06:10.366Z,1367924770.366 [Reporter](INFO): platform_buoyancy_position 868.373718 cc
2013-05-07T11:06:10.614Z,1367924770.614 [Reporter](INFO): platform_buoyancy_position 868.508057 cc
2013-05-07T11:06:11.050Z,1367924771.050 [Reporter](INFO): platform_buoyancy_position 870.522522 cc
2013-05-07T11:06:11.442Z,1367924771.442 [Reporter](INFO): platform_buoyancy_position 870.656860 cc
2013-05-07T11:06:11.982Z,1367924771.982 [Reporter](INFO): platform_buoyancy_position 872.805664 cc
2013-05-07T11:06:12.264Z,1367924772.264 [Reporter](INFO): platform_buoyancy_position 874.820129 cc
2013-05-07T11:06:12.657Z,1367924772.657 [Reporter](INFO): platform_buoyancy_position 874.954468 cc
2013-05-07T11:06:13.054Z,1367924773.054 [Reporter](INFO): platform_buoyancy_position 877.103271 cc
2013-05-07T11:06:13.454Z,1367924773.454 [Reporter](INFO): platform_buoyancy_position 878.983459 cc
2013-05-07T11:06:13.834Z,1367924773.834 [Reporter](INFO): platform_buoyancy_position 879.252075 cc
2013-05-07T11:06:14.247Z,1367924774.247 [Reporter](INFO): platform_buoyancy_position 881.400818 cc
2013-05-07T11:06:14.694Z,1367924774.694 [Reporter](INFO): platform_buoyancy_position 881.938049 cc
2013-05-07T11:06:15.035Z,1367924775.035 [Reporter](INFO): platform_buoyancy_position 883.415344 cc
2013-05-07T11:06:15.449Z,1367924775.449 [Reporter](INFO): platform_buoyancy_position 885.564148 cc
2013-05-07T11:06:16.254Z,1367924776.254 [Reporter](INFO): platform_buoyancy_position 887.847229 cc
2013-05-07T11:06:16.645Z,1367924776.645 [Reporter](INFO): platform_buoyancy_position 888.115845 cc
2013-05-07T11:06:17.060Z,1367924777.060 [Reporter](INFO): platform_buoyancy_position 889.996033 cc
2013-05-07T11:06:17.477Z,1367924777.477 [Reporter](INFO): platform_buoyancy_position 892.010559 cc
2013-05-07T11:06:17.870Z,1367924777.870 [Reporter](INFO): platform_buoyancy_position 892.144836 cc
2013-05-07T11:06:18.306Z,1367924778.306 [Reporter](INFO): platform_buoyancy_position 894.159363 cc
2013-05-07T11:06:18.724Z,1367924778.724 [Reporter](INFO): platform_buoyancy_position 896.173828 cc
2013-05-07T11:06:19.094Z,1367924779.094 [Reporter](INFO): platform_buoyancy_position 896.442444 cc
2013-05-07T11:06:19.501Z,1367924779.501 [Reporter](INFO): platform_buoyancy_position 898.456970 cc
2013-05-07T11:06:19.911Z,1367924779.911 [Reporter](INFO): platform_buoyancy_position 898.591248 cc
2013-05-07T11:06:20.298Z,1367924780.298 [Reporter](INFO): platform_buoyancy_position 900.605774 cc
2013-05-07T11:06:20.700Z,1367924780.700 [Reporter](INFO): platform_buoyancy_position 901.142944 cc
2013-05-07T11:06:21.125Z,1367924781.125 [Reporter](INFO): platform_buoyancy_position 902.754517 cc
2013-05-07T11:06:21.510Z,1367924781.510 [Reporter](INFO): platform_buoyancy_position 904.903320 cc
2013-05-07T11:06:21.898Z,1367924781.898 [Reporter](INFO): platform_buoyancy_position 905.306274 cc
2013-05-07T11:06:22.314Z,1367924782.314 [Reporter](INFO): platform_buoyancy_position 907.052124 cc
2013-05-07T11:06:22.762Z,1367924782.762 [Reporter](INFO): platform_buoyancy_position 909.066650 cc
2013-05-07T11:06:23.194Z,1367924783.194 [Reporter](INFO): platform_buoyancy_position 909.200928 cc
2013-05-07T11:06:23.562Z,1367924783.562 [Reporter](INFO): platform_buoyancy_position 911.349731 cc
2013-05-07T11:06:23.980Z,1367924783.980 [Reporter](INFO): platform_buoyancy_position 913.498535 cc
2013-05-07T11:06:24.730Z,1367924784.730 [Reporter](INFO): platform_buoyancy_position 915.647339 cc
2013-05-07T11:06:25.194Z,1367924785.194 [Reporter](INFO): platform_buoyancy_position 916.050232 cc
2013-05-07T11:06:25.550Z,1367924785.550 [Reporter](INFO): platform_buoyancy_position 917.796143 cc
2013-05-07T11:06:25.965Z,1367924785.965 [Reporter](INFO): platform_buoyancy_position 920.079224 cc
2013-05-07T11:06:26.841Z,1367924786.841 [Reporter](INFO): platform_buoyancy_position 922.093750 cc
2013-05-07T11:06:27.230Z,1367924787.230 [Reporter](INFO): platform_buoyancy_position 922.899536 cc
2013-05-07T11:06:27.655Z,1367924787.655 [Reporter](INFO): platform_buoyancy_position 924.242554 cc
2013-05-07T11:06:28.107Z,1367924788.107 [Reporter](INFO): platform_buoyancy_position 926.525635 cc
2013-05-07T11:06:28.403Z,1367924788.403 [Reporter](INFO): platform_buoyancy_position 926.391357 cc
2013-05-07T11:06:28.802Z,1367924788.802 [Reporter](INFO): platform_buoyancy_position 928.540161 cc
2013-05-07T11:06:29.266Z,1367924789.266 [Reporter](INFO): platform_buoyancy_position 930.688965 cc
2013-05-07T11:06:29.986Z,1367924789.986 [Reporter](INFO): platform_buoyancy_position 932.837769 cc
2013-05-07T11:06:30.801Z,1367924790.801 [Reporter](INFO): platform_buoyancy_position 934.986572 cc
2013-05-07T11:06:31.198Z,1367924791.198 [Reporter](INFO): platform_buoyancy_position 935.120850 cc
2013-05-07T11:06:31.598Z,1367924791.598 [Reporter](INFO): platform_buoyancy_position 937.135376 cc
2013-05-07T11:06:32.050Z,1367924792.050 [Reporter](INFO): platform_buoyancy_position 939.149902 cc
2013-05-07T11:06:32.450Z,1367924792.450 [Reporter](INFO): platform_buoyancy_position 939.418457 cc
2013-05-07T11:06:32.858Z,1367924792.858 [Reporter](INFO): platform_buoyancy_position 941.432983 cc
2013-05-07T11:07:00.642Z,1367924820.642 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2013-05-07T11:07:19.667Z,1367924839.667 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2013-05-07T11:07:19.667Z,1367924839.667 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2013-05-07T11:07:19.667Z,1367924839.667 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2013-05-07T11:07:19.702Z,1367924839.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2013-05-07T11:07:20.080Z,1367924840.080 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2013-05-07T11:07:20.080Z,1367924840.080 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2013-05-07T11:07:43.483Z,1367924863.483 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:07:43.483Z,1367924863.483 [CTD_Seabird] No Fault, FailCount= 3
2013-05-07T11:07:43.600Z,1367924863.600 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:07:56.652Z,1367924876.652 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:07:56.652Z,1367924876.652 [CTD_Seabird] Communications Fault, FailCount= 1
2013-05-07T11:07:56.652Z,1367924876.652 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:07:56.833Z,1367924876.833 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:07:57.056Z,1367924877.056 [CTD_Seabird](INFO): Powering down
2013-05-07T11:07:58.045Z,1367924878.045 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:07:58.045Z,1367924878.045 [CTD_Seabird] No Fault, FailCount= 1
2013-05-07T11:07:58.412Z,1367924878.412 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:08:11.464Z,1367924891.464 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:08:11.464Z,1367924891.464 [CTD_Seabird] Communications Fault, FailCount= 2
2013-05-07T11:08:11.464Z,1367924891.464 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:08:11.818Z,1367924891.818 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:08:11.868Z,1367924891.868 [CTD_Seabird](INFO): Powering down
2013-05-07T11:08:13.013Z,1367924893.013 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:08:13.013Z,1367924893.013 [CTD_Seabird] No Fault, FailCount= 2
2013-05-07T11:08:13.212Z,1367924893.212 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:08:26.284Z,1367924906.284 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:08:26.284Z,1367924906.284 [CTD_Seabird] Communications Fault, FailCount= 3
2013-05-07T11:08:26.284Z,1367924906.284 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:08:26.317Z,1367924906.317 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:08:26.318Z,1367924906.318 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2013-05-07T11:08:26.688Z,1367924906.688 [CTD_Seabird](INFO): Powering down
2013-05-07T11:08:58.228Z,1367924938.228 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2013-05-07T11:08:58.230Z,1367924938.230 [BPC1](INFO): Received data from all battery sticks.
2013-05-07T11:09:20.836Z,1367924960.836 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2013-05-07T11:09:20.836Z,1367924960.836 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2013-05-07T11:09:20.836Z,1367924960.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2013-05-07T11:09:20.872Z,1367924960.872 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2013-05-07T11:09:21.249Z,1367924961.249 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2013-05-07T11:09:21.249Z,1367924961.249 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2013-05-07T11:10:42.857Z,1367925042.857 [Default:CheckIn:Read_GPS](INFO): Timed out from 2013-05-07T11:05:42.7Z
2013-05-07T11:10:42.857Z,1367925042.857 [Default:CheckIn:Read_GPS] Stopped
2013-05-07T11:10:42.857Z,1367925042.857 [Default:CheckIn:Read_Iridium] Running Loop=1
2013-05-07T11:10:43.261Z,1367925043.261 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-05-07T11:10:44.051Z,1367925044.051 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20130507T110108/Courier0004.lzma
2013-05-07T11:10:45.038Z,1367925045.038 [DataOverHttps](INFO): Moved sent file to Logs/20130507T110108/Courier0004.lzma.bak
2013-05-07T11:10:45.038Z,1367925045.038 [DataOverHttps](INFO): SBD MOMSN=15395029
2013-05-07T11:10:56.658Z,1367925056.658 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:10:56.658Z,1367925056.658 [CTD_Seabird] No Fault, FailCount= 3
2013-05-07T11:10:56.704Z,1367925056.704 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:11:02.752Z,1367925062.752 [DataOverHttps](INFO): Sending 498 bytes from file Logs/20130507T110108/Express0005.lzma
2013-05-07T11:11:03.753Z,1367925063.753 [DataOverHttps](INFO): Moved sent file to Logs/20130507T110108/Express0005.lzma.bak
2013-05-07T11:11:03.754Z,1367925063.754 [DataOverHttps](INFO): SBD MOMSN=15395031
2013-05-07T11:11:09.764Z,1367925069.764 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:11:09.764Z,1367925069.764 [CTD_Seabird] Communications Fault, FailCount= 1
2013-05-07T11:11:09.764Z,1367925069.764 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:11:09.989Z,1367925069.989 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:11:10.168Z,1367925070.168 [CTD_Seabird](INFO): Powering down
2013-05-07T11:11:11.178Z,1367925071.178 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:11:11.178Z,1367925071.178 [CTD_Seabird] No Fault, FailCount= 1
2013-05-07T11:11:11.512Z,1367925071.512 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:11:21.673Z,1367925081.673 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2013-05-07T11:11:21.673Z,1367925081.673 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2013-05-07T11:11:21.673Z,1367925081.673 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2013-05-07T11:11:21.686Z,1367925081.686 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2013-05-07T11:11:22.108Z,1367925082.108 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2013-05-07T11:11:22.108Z,1367925082.108 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2013-05-07T11:11:24.065Z,1367925084.065 [DataOverHttps](INFO): Sending 2830 bytes from file Logs/20210212T102539/Express0278.lzma
2013-05-07T11:11:24.564Z,1367925084.564 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:11:24.564Z,1367925084.564 [CTD_Seabird] Communications Fault, FailCount= 2
2013-05-07T11:11:24.564Z,1367925084.564 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:11:24.917Z,1367925084.917 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:11:24.968Z,1367925084.968 [CTD_Seabird](INFO): Powering down
2013-05-07T11:11:25.062Z,1367925085.062 [DataOverHttps](INFO): Moved sent file to Logs/20210212T102539/Express0278.lzma.bak
2013-05-07T11:11:25.062Z,1367925085.062 [DataOverHttps](INFO): SBD MOMSN=15395050
2013-05-07T11:11:26.173Z,1367925086.173 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:11:26.173Z,1367925086.173 [CTD_Seabird] No Fault, FailCount= 2
2013-05-07T11:11:26.308Z,1367925086.308 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:11:39.360Z,1367925099.360 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:11:39.360Z,1367925099.360 [CTD_Seabird] Communications Fault, FailCount= 3
2013-05-07T11:11:39.360Z,1367925099.360 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:11:39.477Z,1367925099.477 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:11:39.478Z,1367925099.478 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2013-05-07T11:11:39.764Z,1367925099.764 [CTD_Seabird](INFO): Powering down
2013-05-07T11:11:59.641Z,1367925119.641 [NAL9602](FAULT): GPS failed to acquire within timeout.
2013-05-07T11:11:59.641Z,1367925119.641 [NAL9602] Data Fault, FailCount= 1
2013-05-07T11:11:59.641Z,1367925119.641 [NAL9602](ERROR): Data Fault
2013-05-07T11:11:59.678Z,1367925119.678 [CBIT](ERROR): Data Fault in component: NAL9602
2013-05-07T11:12:00.045Z,1367925120.045 [NAL9602](INFO): Powering down
2013-05-07T11:12:00.900Z,1367925120.900 [CBIT](INFO): Clearing failed state for component NAL9602
2013-05-07T11:12:00.900Z,1367925120.900 [NAL9602] No Fault, FailCount= 1
2013-05-07T11:12:30.345Z,1367925150.345 [NAL9602](INFO): Powering up NAL9602
2013-05-07T11:12:41.254Z,1367925161.254 [NAL9602](INFO): NAL9602 initialized
2013-05-07T11:12:49.205Z,1367925169.205 [DataOverHttps](INFO): Sending 2995 bytes from file Logs/20210212T102539/Express0281.lzma
2013-05-07T11:12:50.202Z,1367925170.202 [DataOverHttps](INFO): Moved sent file to Logs/20210212T102539/Express0281.lzma.bak
2013-05-07T11:12:50.202Z,1367925170.202 [DataOverHttps](INFO): SBD MOMSN=15395531
2013-05-07T11:13:22.887Z,1367925202.887 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2013-05-07T11:13:22.887Z,1367925202.887 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2013-05-07T11:13:22.887Z,1367925202.887 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2013-05-07T11:13:22.917Z,1367925202.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2013-05-07T11:13:23.327Z,1367925203.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2013-05-07T11:13:23.327Z,1367925203.327 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2013-05-07T11:14:09.759Z,1367925249.759 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:14:09.759Z,1367925249.759 [CTD_Seabird] No Fault, FailCount= 3
2013-05-07T11:14:09.824Z,1367925249.824 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:14:16.093Z,1367925256.093 [DataOverHttps](INFO): Sending 2765 bytes from file Logs/20210212T102539/Express0284.lzma
2013-05-07T11:14:17.090Z,1367925257.090 [DataOverHttps](INFO): Moved sent file to Logs/20210212T102539/Express0284.lzma.bak
2013-05-07T11:14:17.090Z,1367925257.090 [DataOverHttps](INFO): SBD MOMSN=15396012
2013-05-07T11:14:22.876Z,1367925262.876 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:14:22.876Z,1367925262.876 [CTD_Seabird] Communications Fault, FailCount= 1
2013-05-07T11:14:22.876Z,1367925262.876 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:14:23.101Z,1367925263.101 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:14:23.280Z,1367925263.280 [CTD_Seabird](INFO): Powering down
2013-05-07T11:14:24.288Z,1367925264.288 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:14:24.288Z,1367925264.288 [CTD_Seabird] No Fault, FailCount= 1
2013-05-07T11:14:24.624Z,1367925264.624 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:14:37.676Z,1367925277.676 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:14:37.676Z,1367925277.676 [CTD_Seabird] Communications Fault, FailCount= 2
2013-05-07T11:14:37.676Z,1367925277.676 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:14:37.771Z,1367925277.771 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:14:38.080Z,1367925278.080 [CTD_Seabird](INFO): Powering down
2013-05-07T11:14:39.237Z,1367925279.237 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2013-05-07T11:14:39.237Z,1367925279.237 [CTD_Seabird] No Fault, FailCount= 2
2013-05-07T11:14:39.424Z,1367925279.424 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2013-05-07T11:14:52.476Z,1367925292.476 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2013-05-07T11:14:52.476Z,1367925292.476 [CTD_Seabird] Communications Fault, FailCount= 3
2013-05-07T11:14:52.476Z,1367925292.476 [CTD_Seabird](ERROR): Communications Fault
2013-05-07T11:14:52.568Z,1367925292.568 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2013-05-07T11:14:52.569Z,1367925292.569 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2013-05-07T11:14:52.896Z,1367925292.896 [CTD_Seabird](INFO): Powering down
2013-05-07T11:15:23.699Z,1367925323.699 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2013-05-07T11:15:23.699Z,1367925323.699 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2013-05-07T11:15:23.699Z,1367925323.699 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2013-05-07T11:15:23.751Z,1367925323.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2013-05-07T11:15:24.108Z,1367925324.108 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2013-05-07T11:15:24.108Z,1367925324.108 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2013-05-07T11:15:41.722Z,1367925341.722 [DataOverHttps](INFO): Sending 2907 bytes from file Logs/20210212T102539/Express0287.lzma
2013-05-07T11:15:42.718Z,1367925342.718 [DataOverHttps](INFO): Moved sent file to Logs/20210212T102539/Express0287.lzma.bak
2013-05-07T11:15:42.718Z,1367925342.718 [DataOverHttps](INFO): SBD MOMSN=15396489
2013-05-07T11:16:18.394Z,1367925378.394 [CBIT](INFO): Clearing failed state for component DropWeight
2013-05-07T11:16:18.394Z,1367925378.394 [DropWeight] No Fault, FailCount= 1
2013-05-07T11:16:53.792Z,1367925413.792 [CommandLine](IMPORTANT): got command maintain clear
2013-05-07T11:16:53.918Z,1367925413.918 [controlThread](DEBUG): Component order: CycleStarter,CANONSampler,ESPComponent,PAR_Licor,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,AMEcho,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2013-05-07T11:16:56.378Z,1367925416.378 [Reporter](INFO): platform_buoyancy_position 949.759521 cc
2013-05-07T11:17:05.073Z,1367925425.073 [CommandLine](IMPORTANT): got command quit
2013-05-07T11:17:06.080Z,1367925426.080 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:06.080Z,1367925426.080 [CommandLine ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:06.124Z,1367925426.124 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2013-05-07T11:17:06.124Z,1367925426.124 [CommandLine ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:06.125Z,1367925426.125 [CommandLine](INFO): Join timeout helper Thread ID is 2185
2013-05-07T11:17:06.125Z,1367925426.125 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-05-07T11:17:06.125Z,1367925426.125 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:06.126Z,1367925426.126 [NavChartDb](INFO): Join timeout helper Thread ID is 2186
2013-05-07T11:17:06.212Z,1367925426.212 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:06.212Z,1367925426.212 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:06.224Z,1367925426.224 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2013-05-07T11:17:06.224Z,1367925426.224 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:06.224Z,1367925426.224 [Radio_Surface](INFO): Join timeout helper Thread ID is 2187
2013-05-07T11:17:06.616Z,1367925426.616 [Radio_Surface](INFO): Powering down
2013-05-07T11:17:06.617Z,1367925426.617 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:06.617Z,1367925426.617 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:06.624Z,1367925426.624 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2013-05-07T11:17:06.624Z,1367925426.624 [Onboard ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:06.624Z,1367925426.624 [Onboard](INFO): Join timeout helper Thread ID is 2188
2013-05-07T11:17:08.268Z,1367925428.268 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:08.268Z,1367925428.268 [Onboard ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:08.276Z,1367925428.276 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2013-05-07T11:17:08.276Z,1367925428.276 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:08.276Z,1367925428.276 [DataOverHttps](INFO): Join timeout helper Thread ID is 2189
2013-05-07T11:17:09.260Z,1367925429.260 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:09.261Z,1367925429.261 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.269Z,1367925429.269 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-05-07T11:17:09.269Z,1367925429.269 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.269Z,1367925429.269 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2190
2013-05-07T11:17:09.388Z,1367925429.388 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:09.388Z,1367925429.388 [WetLabsBB2FL](INFO): Powering down
2013-05-07T11:17:09.389Z,1367925429.389 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.408Z,1367925429.408 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2013-05-07T11:17:09.409Z,1367925429.409 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.409Z,1367925429.409 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2191
2013-05-07T11:17:09.744Z,1367925429.744 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:09.744Z,1367925429.744 [CTD_Seabird](INFO): Powering down
2013-05-07T11:17:09.756Z,1367925429.756 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.773Z,1367925429.773 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-05-07T11:17:09.773Z,1367925429.773 [logger ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.773Z,1367925429.773 [logger](INFO): Join timeout helper Thread ID is 2192
2013-05-07T11:17:09.812Z,1367925429.812 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:09.812Z,1367925429.812 [logger ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.832Z,1367925429.832 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-05-07T11:17:09.833Z,1367925429.833 [CommandLine ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.833Z,1367925429.833 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-05-07T11:17:09.833Z,1367925429.833 [controlThread ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:09.833Z,1367925429.833 [controlThread](INFO): Join timeout helper Thread ID is 2193
2013-05-07T11:17:10.024Z,1367925430.024 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2013-05-07T11:17:10.024Z,1367925430.024 [controlThread](DEBUG): Uninitializing ControlThread
2013-05-07T11:17:10.026Z,1367925430.026 [NAL9602](INFO): Powering down
2013-05-07T11:17:10.027Z,1367925430.027 [DAT](INFO): Powering down
2013-05-07T11:17:10.148Z,1367925430.148 [AMEcho](INFO): Powering down
2013-05-07T11:17:10.149Z,1367925430.149 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2013-05-07T11:17:10.150Z,1367925430.150 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2013-05-07T11:17:10.151Z,1367925430.151 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2013-05-07T11:17:10.151Z,1367925430.151 [MissionManager](INFO): Uninitializing Mission Default
2013-05-07T11:17:10.151Z,1367925430.151 [Default] Stopped
2013-05-07T11:17:10.152Z,1367925430.152 [Default](DEBUG): Aggregate::uninitialize Default
2013-05-07T11:17:10.152Z,1367925430.152 [Default:B.GoToSurface] Stopped
2013-05-07T11:17:10.152Z,1367925430.152 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-07T11:17:10.152Z,1367925430.152 [Default:CheckIn] Stopped
2013-05-07T11:17:10.152Z,1367925430.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2013-05-07T11:17:10.152Z,1367925430.152 [Default:CheckIn:Read_Iridium] Stopped
2013-05-07T11:17:10.154Z,1367925430.154 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-05-07T11:17:10.155Z,1367925430.155 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-05-07T11:17:10.155Z,1367925430.155 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-05-07T11:17:10.155Z,1367925430.155 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-05-07T11:17:10.155Z,1367925430.155 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-05-07T11:17:10.156Z,1367925430.156 [BuoyancyServo](INFO): Powering down
2013-05-07T11:17:10.168Z,1367925430.168 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-05-07T11:17:10.168Z,1367925430.168 [ElevatorServo](INFO): Powering down
2013-05-07T11:17:10.169Z,1367925430.169 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-05-07T11:17:10.169Z,1367925430.169 [MassServo](INFO): Powering down
2013-05-07T11:17:10.170Z,1367925430.170 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-05-07T11:17:10.170Z,1367925430.170 [RudderServo](INFO): Powering down
2013-05-07T11:17:10.170Z,1367925430.170 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-05-07T11:17:10.170Z,1367925430.170 [ThrusterServo](INFO): Powering down
2013-05-07T11:17:10.171Z,1367925430.171 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-05-07T11:17:10.171Z,1367925430.171 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-05-07T11:17:10.172Z,1367925430.172 [CBIT](DEBUG): Uninitialize CBIT Component.
2013-05-07T11:17:10.172Z,1367925430.172 [CBIT](DEBUG): Powering off loads.
2013-05-07T11:17:10.184Z,1367925430.184 [CBIT](DEBUG): Disabling WDT.
2013-05-07T11:17:10.196Z,1367925430.196 [CBIT](DEBUG): Opening all GF detection circuits.
2013-05-07T11:17:10.197Z,1367925430.197 [controlThread ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:10.240Z,1367925430.240 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:10.246Z,1367925430.246 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:10.315Z,1367925430.315 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:10.316Z,1367925430.316 [Onboard ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:10.321Z,1367925430.321 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:10.370Z,1367925430.370 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2013-05-07T11:17:10.430Z,1367925430.430 [logger ThreadHandler](INFO): Thread cancelled.