2012-05-22T14:32:48.029Z,1337697168.029 [Supervisor](DEBUG): Initializing supervisor. 2012-05-22T14:32:48.032Z,1337697168.032 [SyncHandler](DEBUG): Created PCaller Thread at 1077138656 2012-05-22T14:32:48.032Z,1337697168.032 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2012-05-22T14:32:48.033Z,1337697168.033 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 1077335264 2012-05-22T14:32:48.034Z,1337697168.034 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2012-05-22T14:32:48.045Z,1337697168.045 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2012-05-22T14:32:48.046Z,1337697168.046 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 1077531872 2012-05-22T14:32:48.047Z,1337697168.047 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread. 2012-05-22T14:32:48.047Z,1337697168.047 [Supervisor](INFO): Looking for Config files in directory: Config/ 2012-05-22T14:32:48.048Z,1337697168.048 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2012-05-22T14:32:48.308Z,1337697168.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2012-05-22T14:32:48.309Z,1337697168.309 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2012-05-22T14:32:48.483Z,1337697168.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2012-05-22T14:32:48.483Z,1337697168.483 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2012-05-22T14:32:48.563Z,1337697168.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2012-05-22T14:32:48.564Z,1337697168.564 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2012-05-22T14:32:48.741Z,1337697168.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2012-05-22T14:32:48.742Z,1337697168.742 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2012-05-22T14:32:48.861Z,1337697168.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2012-05-22T14:32:48.861Z,1337697168.861 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2012-05-22T14:32:49.068Z,1337697169.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2012-05-22T14:32:49.069Z,1337697169.069 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2012-05-22T14:32:49.209Z,1337697169.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2012-05-22T14:32:49.209Z,1337697169.209 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2012-05-22T14:32:49.458Z,1337697169.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2012-05-22T14:32:49.458Z,1337697169.458 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2012-05-22T14:32:49.551Z,1337697169.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2012-05-22T14:32:49.552Z,1337697169.552 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2012-05-22T14:32:49.916Z,1337697169.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2012-05-22T14:32:49.916Z,1337697169.917 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2012-05-22T14:32:50.020Z,1337697170.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2012-05-22T14:32:50.021Z,1337697170.021 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2012-05-22T14:32:50.101Z,1337697170.101 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2012-05-22T14:32:50.102Z,1337697170.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2012-05-22T14:32:50.199Z,1337697170.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2012-05-22T14:32:50.325Z,1337697170.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2012-05-22T14:32:50.420Z,1337697170.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2012-05-22T14:32:50.510Z,1337697170.510 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2012-05-22T14:32:50.613Z,1337697170.613 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2012-05-22T14:32:50.697Z,1337697170.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2012-05-22T14:32:50.779Z,1337697170.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2012-05-22T14:32:50.886Z,1337697170.885 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2012-05-22T14:32:51.006Z,1337697171.006 [InternalSim] Loaded 2012-05-22T14:32:51.007Z,1337697171.007 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2012-05-22T14:32:51.007Z,1337697171.007 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2012-05-22T14:32:51.008Z,1337697171.008 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2012-05-22T14:32:51.049Z,1337697171.049 [SBIT](DEBUG): Construct Startup Built In Test. 2012-05-22T14:32:51.060Z,1337697171.060 [SBIT] Loaded 2012-05-22T14:32:51.060Z,1337697171.060 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2012-05-22T14:32:51.061Z,1337697171.061 [IBIT](DEBUG): Construct Initiated Built In Test. 2012-05-22T14:32:51.083Z,1337697171.083 [IBIT] Loaded 2012-05-22T14:32:51.084Z,1337697171.084 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2012-05-22T14:32:51.091Z,1337697171.091 [CBIT](DEBUG): Construct CBIT Built In Test. 2012-05-22T14:32:51.193Z,1337697171.193 [CBIT] Loaded 2012-05-22T14:32:51.193Z,1337697171.193 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2012-05-22T14:32:51.194Z,1337697171.194 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2012-05-22T14:32:51.194Z,1337697171.194 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2012-05-22T14:32:51.321Z,1337697171.321 [BuoyancyServo] Loaded 2012-05-22T14:32:51.321Z,1337697171.321 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2012-05-22T14:32:51.328Z,1337697171.328 [ElevatorServo] Loaded 2012-05-22T14:32:51.328Z,1337697171.328 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2012-05-22T14:32:51.334Z,1337697171.334 [MassServo] Loaded 2012-05-22T14:32:51.335Z,1337697171.335 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2012-05-22T14:32:51.341Z,1337697171.341 [RudderServo] Loaded 2012-05-22T14:32:51.341Z,1337697171.341 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2012-05-22T14:32:51.347Z,1337697171.347 [ThrusterServo] Loaded 2012-05-22T14:32:51.348Z,1337697171.348 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2012-05-22T14:32:51.348Z,1337697171.348 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2012-05-22T14:32:51.349Z,1337697171.349 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2012-05-22T14:32:51.363Z,1337697171.363 [DepthRateCalculator] Loaded 2012-05-22T14:32:51.363Z,1337697171.363 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2012-05-22T14:32:51.379Z,1337697171.379 [NavChart] Loaded 2012-05-22T14:32:51.379Z,1337697171.379 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2012-05-22T14:32:51.384Z,1337697171.384 [PitchRateCalculator] Loaded 2012-05-22T14:32:51.384Z,1337697171.385 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2012-05-22T14:32:51.390Z,1337697171.390 [SpeedCalculator] Loaded 2012-05-22T14:32:51.390Z,1337697171.390 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2012-05-22T14:32:51.404Z,1337697171.404 [TempGradientCalculator] Loaded 2012-05-22T14:32:51.404Z,1337697171.404 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2012-05-22T14:32:51.410Z,1337697171.410 [YawRateCalculator] Loaded 2012-05-22T14:32:51.410Z,1337697171.410 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2012-05-22T14:32:51.438Z,1337697171.438 [Navigation] Loaded 2012-05-22T14:32:51.439Z,1337697171.439 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2012-05-22T14:32:51.439Z,1337697171.439 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2012-05-22T14:32:51.440Z,1337697171.440 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2012-05-22T14:32:51.639Z,1337697171.639 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2012-05-22T14:32:51.640Z,1337697171.640 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2012-05-22T14:32:51.660Z,1337697171.660 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2012-05-22T14:32:51.661Z,1337697171.661 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2012-05-22T14:32:51.697Z,1337697171.697 [VerticalControl](DEBUG): Construct VerticalControl. 2012-05-22T14:32:51.739Z,1337697171.739 [VerticalControl] Loaded 2012-05-22T14:32:51.739Z,1337697171.739 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2012-05-22T14:32:51.740Z,1337697171.740 [HorizontalControl](DEBUG): Construct HorizontalControl. 2012-05-22T14:32:51.759Z,1337697171.759 [HorizontalControl] Loaded 2012-05-22T14:32:51.759Z,1337697171.759 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2012-05-22T14:32:51.760Z,1337697171.760 [SpeedControl](DEBUG): Construct SpeedControl. 2012-05-22T14:32:51.762Z,1337697171.762 [SpeedControl] Loaded 2012-05-22T14:32:51.762Z,1337697171.762 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2012-05-22T14:32:51.763Z,1337697171.763 [LoopControl](DEBUG): Construct LoopControl. 2012-05-22T14:32:51.763Z,1337697171.763 [LoopControl] Loaded 2012-05-22T14:32:51.763Z,1337697171.763 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2012-05-22T14:32:51.764Z,1337697171.764 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2012-05-22T14:32:51.764Z,1337697171.764 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2012-05-22T14:32:51.770Z,1337697171.770 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2012-05-22T14:32:51.774Z,1337697171.774 [AsyncPiEstimator] Loaded 2012-05-22T14:32:51.774Z,1337697171.774 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2012-05-22T14:32:51.775Z,1337697171.776 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 1078994144 2012-05-22T14:32:51.776Z,1337697171.776 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2012-05-22T14:32:51.777Z,1337697171.777 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2012-05-22T14:32:51.865Z,1337697171.865 [AHRS_sp3003D] Loaded 2012-05-22T14:32:51.865Z,1337697171.865 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2012-05-22T14:32:52.101Z,1337697172.101 [Batt_Ocean_Server] Loaded 2012-05-22T14:32:52.101Z,1337697172.101 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2012-05-22T14:32:52.112Z,1337697172.112 [Depth_Keller] Loaded 2012-05-22T14:32:52.113Z,1337697172.113 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2012-05-22T14:32:52.118Z,1337697172.118 [DropWeight] Loaded 2012-05-22T14:32:52.118Z,1337697172.118 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2012-05-22T14:32:52.207Z,1337697172.207 [DVL_micro] Loaded 2012-05-22T14:32:52.207Z,1337697172.207 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2012-05-22T14:32:52.282Z,1337697172.282 [NAL9601] Loaded 2012-05-22T14:32:52.282Z,1337697172.282 [ComponentRegistry](DEBUG): SyncComponent "NAL9601" handled in the control thread. 2012-05-22T14:32:52.328Z,1337697172.328 [Onboard] Loaded 2012-05-22T14:32:52.329Z,1337697172.329 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2012-05-22T14:32:52.334Z,1337697172.334 [Radio_Freewave] Loaded 2012-05-22T14:32:52.335Z,1337697172.335 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2012-05-22T14:32:52.335Z,1337697172.335 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2012-05-22T14:32:52.336Z,1337697172.336 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2012-05-22T14:32:52.379Z,1337697172.379 [CTD_NeilBrown] Loaded 2012-05-22T14:32:52.379Z,1337697172.379 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2012-05-22T14:32:52.380Z,1337697172.380 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 1079645408 2012-05-22T14:32:52.409Z,1337697172.409 [WetLabsBB2FL] Loaded 2012-05-22T14:32:52.409Z,1337697172.409 [ComponentRegistry](DEBUG): SyncComponent "WetLabsBB2FL" handled in the control thread. 2012-05-22T14:32:52.410Z,1337697172.409 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2012-05-22T14:32:52.412Z,1337697172.412 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2012-05-22T14:32:52.412Z,1337697172.412 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2012-05-22T14:32:52.418Z,1337697172.418 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2012-05-22T14:32:52.419Z,1337697172.419 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 1079842016 2012-05-22T14:32:52.419Z,1337697172.419 [Supervisor](DEBUG): Running supervisor. 2012-05-22T14:32:52.424Z,1337697172.424 [controlThread](DEBUG): Initializing ControlThread 2012-05-22T14:32:52.424Z,1337697172.424 [InternalSim](DEBUG): InternalSim initializing... 2012-05-22T14:32:52.459Z,1337697172.459 [SBIT](INFO): Initialize SBIT Component. 2012-05-22T14:32:52.460Z,1337697172.460 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 9636 $ 2012-05-22T14:32:52.460Z,1337697172.460 [IBIT](INFO): Initialize IBIT Component. 2012-05-22T14:32:52.463Z,1337697172.463 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2012-05-22T14:32:52.471Z,1337697172.471 [CBIT](DEBUG): Initialize CBIT Component. 2012-05-22T14:32:52.472Z,1337697172.472 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2012-05-22T14:32:52.473Z,1337697172.473 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2012-05-22T14:32:52.496Z,1337697172.496 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-05-22T14:32:52.496Z,1337697172.496 [NavChart](DEBUG): Initialize NavChart Derivation. 2012-05-22T14:32:52.496Z,1337697172.497 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2012-05-22T14:32:52.497Z,1337697172.497 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2012-05-22T14:32:52.498Z,1337697172.497 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2012-05-22T14:32:52.499Z,1337697172.499 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2012-05-22T14:32:52.499Z,1337697172.499 [Navigation](DEBUG): Initializing Navigation. 2012-05-22T14:32:52.500Z,1337697172.500 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2012-05-22T14:32:52.502Z,1337697172.502 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2012-05-22T14:32:52.502Z,1337697172.502 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2012-05-22T14:32:52.503Z,1337697172.503 [LoopControl](DEBUG): Initialize LoopControlComponent. 2012-05-22T14:32:52.582Z,1337697172.582 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2012-05-22T14:32:52.587Z,1337697172.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2012-05-22T14:32:52.591Z,1337697172.591 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2012-05-22T14:32:52.595Z,1337697172.595 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2012-05-22T14:32:52.599Z,1337697172.599 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2012-05-22T14:32:52.600Z,1337697172.600 [NavChartDb](INFO): Creating index for Soundings 2012-05-22T14:32:52.603Z,1337697172.603 [NavChartDb](INFO): Creating index for Contour 0p0 2012-05-22T14:32:52.605Z,1337697172.605 [NavChartDb](INFO): Creating index for Contour 1p8 2012-05-22T14:32:52.607Z,1337697172.607 [NavChartDb](INFO): Creating index for Contour 3p6 2012-05-22T14:32:52.609Z,1337697172.609 [NavChartDb](INFO): Creating index for Contour 5p4 2012-05-22T14:32:52.611Z,1337697172.611 [NavChartDb](INFO): Creating index for Contour 9p1 2012-05-22T14:32:52.613Z,1337697172.613 [NavChartDb](INFO): Creating index for Contour 10p9 2012-05-22T14:32:52.615Z,1337697172.615 [NavChartDb](INFO): Creating index for Contour 18p2 2012-05-22T14:32:52.617Z,1337697172.617 [NavChartDb](INFO): Creating index for Contour 36p5 2012-05-22T14:32:52.619Z,1337697172.619 [NavChartDb](INFO): Creating index for Contour 54p8 2012-05-22T14:32:52.621Z,1337697172.621 [NavChartDb](INFO): Creating index for Contour 73p1 2012-05-22T14:32:52.623Z,1337697172.624 [NavChartDb](INFO): Creating index for Contour 91p4 2012-05-22T14:32:52.625Z,1337697172.625 [NavChartDb](INFO): Creating index for Contour 182p8 2012-05-22T14:32:52.627Z,1337697172.627 [NavChartDb](INFO): Creating index for Contour 365p7 2012-05-22T14:32:52.629Z,1337697172.629 [NavChartDb](INFO): Creating index for Contour 548p6 2012-05-22T14:32:52.632Z,1337697172.632 [NavChartDb](INFO): Creating index for Contour 731p5 2012-05-22T14:32:52.633Z,1337697172.633 [NavChartDb](INFO): Creating index for Contour 914p4 2012-05-22T14:32:52.636Z,1337697172.635 [NavChartDb](INFO): Creating index for Contour 1097p2 2012-05-22T14:32:52.637Z,1337697172.637 [NavChartDb](INFO): Creating index for Contour 1280p1 2012-05-22T14:32:52.640Z,1337697172.639 [NavChartDb](INFO): Creating index for Contour 1463p0 2012-05-22T14:32:52.641Z,1337697172.641 [NavChartDb](INFO): Creating index for Contour 1645p9 2012-05-22T14:32:52.643Z,1337697172.643 [NavChartDb](INFO): Creating index for Contour 1828p8 2012-05-22T14:32:52.645Z,1337697172.645 [NavChartDb](INFO): Creating index for Contour 2011p6 2012-05-22T14:32:52.647Z,1337697172.647 [NavChartDb](INFO): Creating index for Contour 2194p5 2012-05-22T14:32:52.649Z,1337697172.649 [NavChartDb](INFO): Creating index for Contour 2377p4 2012-05-22T14:32:52.651Z,1337697172.651 [NavChartDb](INFO): Creating index for Contour 2560p3 2012-05-22T14:32:52.653Z,1337697172.653 [NavChartDb](INFO): Creating index for Contour 2743p2 2012-05-22T14:32:52.655Z,1337697172.655 [NavChartDb](INFO): Creating index for Contour 2926p0 2012-05-22T14:32:52.657Z,1337697172.657 [NavChartDb](INFO): Creating index for Contour 3108p9 2012-05-22T14:32:52.659Z,1337697172.659 [NavChartDb](INFO): Creating index for Contour 3291p8 2012-05-22T14:32:52.660Z,1337697172.660 [NavChartDb](INFO): Creating index for Contour 3474p7 2012-05-22T14:32:52.662Z,1337697172.662 [NavChartDb](INFO): Creating index for Contour 3657p6 2012-05-22T14:32:53.951Z,1337697173.951 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2012-05-22T14:32:53.961Z,1337697173.961 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2012-05-22T14:32:53.972Z,1337697173.972 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-05-22T14:32:53.981Z,1337697173.981 [MissionManager](DEBUG): 2012-05-22T14:32:53.982Z,1337697173.982 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2012-05-22T14:32:54.009Z,1337697174.009 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-05-22T14:32:54.011Z,1337697174.011 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-05-22T14:32:54.014Z,1337697174.014 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-05-22T14:32:54.022Z,1337697174.022 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-05-22T14:32:54.025Z,1337697174.025 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-05-22T14:32:54.031Z,1337697174.031 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-05-22T14:32:54.039Z,1337697174.039 [Default:D.SetSpeed](DEBUG): Construct. 2012-05-22T14:32:54.042Z,1337697174.042 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-05-22T14:32:54.047Z,1337697174.047 [Default:F.Wait](DEBUG): Construct Wait. 2012-05-22T14:32:54.050Z,1337697174.050 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-05-22T14:32:54.055Z,1337697174.055 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger, 2012-05-22T14:32:54.074Z,1337697174.074 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-05-22T14:32:54.181Z,1337697174.181 [DVL_micro](DEBUG): Initializing DVL_micro. 2012-05-22T14:32:54.203Z,1337697174.203 [Radio_Freewave](INFO): Powering up 2012-05-22T14:32:54.208Z,1337697174.208 [WetLabsBB2FL](INFO): Powering down 2012-05-22T14:32:54.277Z,1337697174.277 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2012-05-22T14:32:54.278Z,1337697174.278 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2012-05-22T14:32:54.287Z,1337697174.287 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-05-22T14:32:54.288Z,1337697174.288 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-05-22T14:32:54.296Z,1337697174.296 [MassServo](DEBUG): Initializing EZServoServo. 2012-05-22T14:32:54.297Z,1337697174.297 [MassServo](DEBUG): Initializing MassServo. 2012-05-22T14:32:54.305Z,1337697174.305 [RudderServo](DEBUG): Initializing EZServoServo. 2012-05-22T14:32:54.306Z,1337697174.306 [RudderServo](DEBUG): Initializing RudderServo. 2012-05-22T14:32:54.314Z,1337697174.314 [ThrusterServo](DEBUG): Initializing EZServoServo. 2012-05-22T14:32:54.315Z,1337697174.315 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2012-05-22T14:32:57.562Z,1337697177.562 [NAL9601](INFO): Powering up NAL9601 2012-05-22T14:33:08.181Z,1337697188.181 [SBIT](IMPORTANT): Beginning Startup BIT 2012-05-22T14:33:08.184Z,1337697188.184 [CBIT](IMPORTANT): Beginning GF scan 2012-05-22T14:33:34.328Z,1337697214.328 [CBIT](IMPORTANT): No ground fault detected 2012-05-22T14:33:50.286Z,1337697230.286 [SBIT](IMPORTANT): SBIT PASSED 2012-05-22T14:33:50.715Z,1337697230.715 [MissionManager](IMPORTANT): Started mission Startup 2012-05-22T14:33:50.715Z,1337697230.715 [Startup] Running Loop=1 2012-05-22T14:33:50.715Z,1337697230.715 [Startup](INFO): Aggregate::initialize Startup 2012-05-22T14:33:50.715Z,1337697230.716 [Startup:A.GoToSurface] Running Loop=1 2012-05-22T14:33:50.715Z,1337697230.716 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:33:50.721Z,1337697230.721 [Startup:StartupSatComms] Running Loop=1 2012-05-22T14:33:50.722Z,1337697230.722 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2012-05-22T14:33:50.722Z,1337697230.722 [Startup:StartupSatComms:A] Running Loop=1 2012-05-22T14:33:51.080Z,1337697231.080 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-05-22T14:34:03.103Z,1337697243.103 [NAL9601](INFO): NAL9601 initialized 2012-05-22T14:34:51.095Z,1337697291.095 [Startup:StartupSatComms:A](INFO): Timed out from 2012-05-22T14:33:50.7Z 2012-05-22T14:34:51.095Z,1337697291.095 [Startup:StartupSatComms:A_Timeout] Running Loop=1 2012-05-22T14:34:51.095Z,1337697291.095 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout 2012-05-22T14:34:51.096Z,1337697291.096 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout 2012-05-22T14:34:51.096Z,1337697291.096 [Startup:StartupSatComms:A] Stopped 2012-05-22T14:34:51.096Z,1337697291.096 [Startup:StartupSatComms:B] Running Loop=1 2012-05-22T14:34:51.483Z,1337697291.483 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-05-22T14:35:51.401Z,1337697351.401 [Startup:StartupSatComms:B](INFO): Timed out from 2012-05-22T14:34:51.1Z 2012-05-22T14:35:51.401Z,1337697351.401 [Startup:StartupSatComms:A_Timeout] Running Loop=1 2012-05-22T14:35:51.401Z,1337697351.401 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout 2012-05-22T14:35:51.401Z,1337697351.401 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout 2012-05-22T14:35:51.401Z,1337697351.401 [Startup:StartupSatComms:B] Stopped 2012-05-22T14:35:51.402Z,1337697351.402 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2012-05-22T14:35:51.402Z,1337697351.402 [Startup:StartupSatComms] Stopped 2012-05-22T14:35:51.402Z,1337697351.402 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2012-05-22T14:35:51.404Z,1337697351.404 [Startup](INFO): Completed Startup 2012-05-22T14:35:51.404Z,1337697351.404 [Startup] Stopped 2012-05-22T14:35:51.404Z,1337697351.404 [Startup](INFO): Aggregate::uninitialize Startup 2012-05-22T14:35:51.404Z,1337697351.404 [Startup:A.GoToSurface] Stopped 2012-05-22T14:35:51.404Z,1337697351.404 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:35:51.795Z,1337697351.796 [MissionManager](IMPORTANT): Started mission Default 2012-05-22T14:35:51.796Z,1337697351.796 [Default] Running Loop=1 2012-05-22T14:35:51.796Z,1337697351.796 [Default](INFO): Aggregate::initialize Default 2012-05-22T14:35:51.796Z,1337697351.796 [Default:D.SetSpeed] Running Loop=1 2012-05-22T14:35:51.796Z,1337697351.796 [Default:D.SetSpeed](DEBUG): Initialize. 2012-05-22T14:35:51.796Z,1337697351.796 [Default:E.GoToSurface] Running Loop=1 2012-05-22T14:35:51.796Z,1337697351.796 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:35:51.797Z,1337697351.797 [Default:Iridium] Running Loop=1 2012-05-22T14:35:51.797Z,1337697351.797 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-05-22T14:35:51.797Z,1337697351.797 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-05-22T14:35:51.797Z,1337697351.797 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-05-22T14:35:51.797Z,1337697351.797 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-05-22T14:35:51.797Z,1337697351.797 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:35:51.798Z,1337697351.798 [Default:E.GoToSurface] Running Loop=1 2012-05-22T14:35:51.807Z,1337697351.807 [Default:D.SetSpeed] Running Loop=1 2012-05-22T14:35:51.812Z,1337697351.812 [Default:CallIridium] Running Loop=1 2012-05-22T14:35:51.812Z,1337697351.812 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-05-22T14:35:51.812Z,1337697351.812 [Default:CallIridium:A] Running Loop=1 2012-05-22T14:35:51.813Z,1337697351.813 [Default:CallIridium:A] Stopped 2012-05-22T14:35:51.813Z,1337697351.813 [Default:CallIridium:B] Running Loop=1 2012-05-22T14:35:51.814Z,1337697351.814 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-05-22T14:35:51.819Z,1337697351.819 [Default:Iridium:B.GoToSurface] Stopped 2012-05-22T14:35:51.819Z,1337697351.819 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:35:51.819Z,1337697351.819 [Default:Iridium:Read_Iridium] Running Loop=1 2012-05-22T14:35:51.819Z,1337697351.819 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-05-22T14:35:51.824Z,1337697351.824 [Default:GPS] Running Loop=1 2012-05-22T14:35:51.824Z,1337697351.824 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-05-22T14:35:51.824Z,1337697351.824 [Default:GPS:A.SetSpeed] Running Loop=1 2012-05-22T14:35:51.824Z,1337697351.824 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-05-22T14:35:51.824Z,1337697351.824 [Default:GPS:B.GoToSurface] Running Loop=1 2012-05-22T14:35:51.825Z,1337697351.825 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:35:51.830Z,1337697351.830 [Default:GPS:B.GoToSurface] Stopped 2012-05-22T14:35:51.830Z,1337697351.830 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:35:51.830Z,1337697351.830 [Default:GPS:Read_GPS] Running Loop=1 2012-05-22T14:35:51.830Z,1337697351.830 [Default:GPS:A.SetSpeed] Running Loop=1 2012-05-22T14:35:52.197Z,1337697352.197 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-05-22T14:35:52.200Z,1337697352.200 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-05-22T14:44:06.352Z,1337697846.352 [NAL9601](FAULT): GPS failed to acquire within timeout. 2012-05-22T14:44:06.352Z,1337697846.352 [NAL9601] Data Fault, FailCount= 1 2012-05-22T14:44:06.352Z,1337697846.352 [NAL9601](ERROR): Data Fault 2012-05-22T14:44:06.373Z,1337697846.374 [CBIT](ERROR): Data Fault in component: NAL9601 2012-05-22T14:44:06.752Z,1337697846.752 [NAL9601](INFO): Powering down 2012-05-22T14:44:06.775Z,1337697846.774 [CBIT](INFO): Clearing failed state for component NAL9601 2012-05-22T14:44:06.775Z,1337697846.775 [NAL9601] No Fault, FailCount= 1 2012-05-22T14:44:11.954Z,1337697851.954 [NAL9601](INFO): Powering up NAL9601 2012-05-22T14:45:17.591Z,1337697917.591 [NAL9601](INFO): NAL9601 initialized 2012-05-22T14:46:20.142Z,1337697980.142 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:22.724Z,1337697982.724 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:25.120Z,1337697985.120 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:27.324Z,1337697987.324 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:29.908Z,1337697989.908 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:31.912Z,1337697991.912 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:34.489Z,1337697994.489 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:36.887Z,1337697996.887 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:39.095Z,1337697999.095 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:41.679Z,1337698001.679 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:44.079Z,1337698004.079 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:46.304Z,1337698006.304 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:48.848Z,1337698008.848 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:51.283Z,1337698011.283 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:53.424Z,1337698013.424 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:55.828Z,1337698015.828 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:46:58.431Z,1337698018.431 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:01.012Z,1337698021.012 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:03.012Z,1337698023.012 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:05.595Z,1337698025.595 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:07.999Z,1337698028.000 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:10.179Z,1337698030.179 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:12.803Z,1337698032.803 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:14.802Z,1337698034.802 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:17.375Z,1337698037.375 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:19.955Z,1337698039.955 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:21.955Z,1337698041.955 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:24.539Z,1337698044.539 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:26.940Z,1337698046.940 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:47:29.150Z,1337698049.150 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:50:37.034Z,1337698237.034 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/tank_weight.xml 2012-05-22T14:50:37.034Z,1337698237.034 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_weight.xml 2012-05-22T14:50:37.090Z,1337698237.090 [MissionManager](INFO): DefineArg tank_weight.BuoyancyLimitHiCC = 0.000955 n/a 2012-05-22T14:50:37.097Z,1337698237.097 [MissionManager](INFO): DefineArg tank_weight.BuoyancyLimitLoCC = 0.000080 n/a 2012-05-22T14:50:37.104Z,1337698237.104 [tank_weight:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-05-22T14:50:37.113Z,1337698237.113 [tank_weight:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-05-22T14:50:37.138Z,1337698237.138 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2012-05-22T14:50:37.224Z,1337698237.224 [MissionManager](INFO): DefineArg tank_weight:Science.SampleAanderaaO2 = 0.000000 n/a 2012-05-22T14:50:37.230Z,1337698237.230 [MissionManager](INFO): DefineArg tank_weight:Science.SampleISUS = 0 bool 2012-05-22T14:50:37.238Z,1337698237.238 [MissionManager](INFO): DefineArg tank_weight:Science.SampleNeilBrown = 1.000000 n/a 2012-05-22T14:50:37.241Z,1337698237.241 [MissionManager](INFO): DefineArg tank_weight:Science.SampleWetLabsBB2FL = 1.000000 n/a 2012-05-22T14:50:37.244Z,1337698237.244 [MissionManager](INFO): DefineArg tank_weight:Science.PeakDetectChlActive = 0 bool 2012-05-22T14:50:37.263Z,1337698237.263 [MissionManager](INFO): DefineArg tank_weight:Science.PeakDetectNO3Active = 0 bool 2012-05-22T14:50:37.270Z,1337698237.270 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChl = 0.000000 ug/l 2012-05-22T14:50:37.273Z,1337698237.273 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlDepth = 0.000000 m 2012-05-22T14:50:37.280Z,1337698237.280 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlLatitude = nan arcdeg 2012-05-22T14:50:37.287Z,1337698237.287 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlLongitude = nan arcdeg 2012-05-22T14:50:37.295Z,1337698237.295 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3 = 0.000000 uM/l 2012-05-22T14:50:37.298Z,1337698237.298 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Depth = 0.000000 m 2012-05-22T14:50:37.305Z,1337698237.305 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Latitude = nan arcdeg 2012-05-22T14:50:37.312Z,1337698237.312 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Longitude = nan arcdeg 2012-05-22T14:50:37.332Z,1337698237.332 [tank_weight:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-05-22T14:50:37.349Z,1337698237.349 [tank_weight:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-05-22T14:50:37.366Z,1337698237.366 [MissionManager](INFO): RedefineArg tank_weight:Science.SampleISUS = 1 bool 2012-05-22T14:50:37.372Z,1337698237.372 [tank_weight:D:A.SetSpeed](DEBUG): Construct. 2012-05-22T14:50:37.379Z,1337698237.379 [tank_weight:D:B.Pitch](DEBUG): Construct. 2012-05-22T14:50:37.405Z,1337698237.405 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Construct Buoyancy. 2012-05-22T14:50:37.409Z,1337698237.409 [tank_weight:D:BuoyancyLo:B.Wait](DEBUG): Construct Wait. 2012-05-22T14:50:37.412Z,1337698237.411 [tank_weight:D:BuoyancyHi:A.Buoyancy](DEBUG): Construct Buoyancy. 2012-05-22T14:50:37.415Z,1337698237.415 [tank_weight:D:BuoyancyHi:B.Wait](DEBUG): Construct Wait. 2012-05-22T14:50:37.438Z,1337698237.438 [MissionManager](DEBUG): 0 0 15 0 0 15 65 5 2012-05-22T14:50:37.438Z,1337698237.438 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/tank_weight.xml 2012-05-22T14:50:37.651Z,1337698237.651 [Default] Stopped 2012-05-22T14:50:37.651Z,1337698237.651 [Default](INFO): Aggregate::uninitialize Default 2012-05-22T14:50:37.651Z,1337698237.651 [Default:GPS] Stopped 2012-05-22T14:50:37.651Z,1337698237.651 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-05-22T14:50:37.651Z,1337698237.651 [Default:GPS:A.SetSpeed] Stopped 2012-05-22T14:50:37.651Z,1337698237.651 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-05-22T14:50:37.651Z,1337698237.651 [Default:GPS:Read_GPS] Stopped 2012-05-22T14:50:37.652Z,1337698237.652 [Default:Iridium] Stopped 2012-05-22T14:50:37.652Z,1337698237.652 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-05-22T14:50:37.652Z,1337698237.652 [Default:Iridium:A.SetSpeed] Stopped 2012-05-22T14:50:37.652Z,1337698237.652 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-05-22T14:50:37.652Z,1337698237.652 [Default:Iridium:Read_Iridium] Stopped 2012-05-22T14:50:37.652Z,1337698237.652 [Default:CallIridium] Stopped 2012-05-22T14:50:37.652Z,1337698237.652 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-05-22T14:50:37.652Z,1337698237.652 [Default:CallIridium:B] Stopped 2012-05-22T14:50:37.652Z,1337698237.652 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-05-22T14:50:37.652Z,1337698237.652 [Default:D.SetSpeed] Stopped 2012-05-22T14:50:37.652Z,1337698237.652 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-05-22T14:50:37.652Z,1337698237.652 [Default:E.GoToSurface] Stopped 2012-05-22T14:50:37.653Z,1337698237.653 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:50:37.653Z,1337698237.653 [MissionManager](IMPORTANT): Started mission tank_weight 2012-05-22T14:50:37.653Z,1337698237.653 [tank_weight] Running Loop=1 2012-05-22T14:50:37.653Z,1337698237.653 [tank_weight](INFO): Aggregate::initialize tank_weight 2012-05-22T14:50:37.653Z,1337698237.653 [tank_weight:A.AltitudeEnvelope] Running Loop=1 2012-05-22T14:50:37.653Z,1337698237.653 [tank_weight:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-05-22T14:50:37.653Z,1337698237.653 [tank_weight:B.DepthEnvelope] Running Loop=1 2012-05-22T14:50:37.653Z,1337698237.653 [tank_weight:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:Science] Running Loop=1 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:Science](INFO): Aggregate::initialize tank_weight:Science 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:Science:A] Running Loop=1 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:Science:B] Running Loop=1 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:Science:C] Running Loop=1 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:Science:D] Running Loop=1 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:D] Running Loop=1 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:D](INFO): Aggregate::initialize tank_weight:D 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:D:A.SetSpeed] Running Loop=1 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:D:A.SetSpeed](DEBUG): Initialize. 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:D:B.Pitch] Running Loop=1 2012-05-22T14:50:37.655Z,1337698237.655 [tank_weight:D:B.Pitch](DEBUG): Initialize. 2012-05-22T14:50:37.656Z,1337698237.656 [tank_weight:D:BuoyancyLo] Running Loop=1 2012-05-22T14:50:37.656Z,1337698237.656 [tank_weight:D:BuoyancyLo](INFO): Aggregate::initialize tank_weight:D:BuoyancyLo 2012-05-22T14:50:37.656Z,1337698237.656 [tank_weight:D:BuoyancyLo:A.Buoyancy] Running Loop=1 2012-05-22T14:50:37.656Z,1337698237.656 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-05-22T14:50:37.657Z,1337698237.657 [tank_weight:D:BuoyancyLo:B.Wait] Running Loop=1 2012-05-22T14:50:37.657Z,1337698237.657 [tank_weight:D:BuoyancyLo:B.Wait](DEBUG): Initialize Wait Component. 2012-05-22T14:50:37.661Z,1337698237.661 [tank_weight:D:BuoyancyLo:A.Buoyancy] Running Loop=1 2012-05-22T14:50:37.666Z,1337698237.666 [tank_weight:D:B.Pitch] Running Loop=1 2012-05-22T14:50:37.671Z,1337698237.671 [tank_weight:D:A.SetSpeed] Running Loop=1 2012-05-22T14:50:37.675Z,1337698237.675 [tank_weight:Science] Running Loop=1 2012-05-22T14:50:37.676Z,1337698237.676 [tank_weight:Science:D] Running Loop=1 2012-05-22T14:50:37.677Z,1337698237.677 [tank_weight:Science:D](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2012-05-22T14:50:37.678Z,1337698237.678 [tank_weight:Science:C] Running Loop=1 2012-05-22T14:50:37.680Z,1337698237.680 [tank_weight:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-05-22T14:50:37.681Z,1337698237.681 [tank_weight:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-05-22T14:50:37.682Z,1337698237.682 [tank_weight:Science:B] Running Loop=1 2012-05-22T14:50:37.683Z,1337698237.683 [tank_weight:Science:B](DEBUG): Initialize ReadDataComponent to sense mole_concentration_of_nitrate_in_sea_water 2012-05-22T14:50:37.684Z,1337698237.684 [tank_weight:B.DepthEnvelope] Running Loop=1 2012-05-22T14:50:37.689Z,1337698237.689 [tank_weight:A.AltitudeEnvelope] Running Loop=1 2012-05-22T14:50:38.008Z,1337698238.008 [WetLabsBB2FL](INFO): Powering up 2012-05-22T14:50:39.220Z,1337698239.220 [tank_weight:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2012-05-22T14:50:40.168Z,1337698240.168 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.075510 psu 2012-05-22T14:50:40.372Z,1337698240.372 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.001260 psu 2012-05-22T14:50:40.373Z,1337698240.373 [CTD_NeilBrown](ERROR): Pressure reading out of range: -471.549988 decibar 2012-05-22T14:50:40.575Z,1337698240.575 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.065930 psu 2012-05-22T14:50:40.779Z,1337698240.779 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.040130 psu 2012-05-22T14:50:40.987Z,1337698240.987 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.058100 psu 2012-05-22T14:50:41.191Z,1337698241.191 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037760 psu 2012-05-22T14:50:41.395Z,1337698241.395 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.027170 psu 2012-05-22T14:50:41.600Z,1337698241.599 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036010 psu 2012-05-22T14:50:41.613Z,1337698241.614 [WetLabsBB2FL](DEBUG): Error parsing data: 2012-05-22T14:50:41.613Z,1337698241.614 [WetLabsBB2FL] Data Fault, FailCount= 1 2012-05-22T14:50:41.614Z,1337698241.614 [WetLabsBB2FL](ERROR): Data Fault 2012-05-22T14:50:41.655Z,1337698241.655 [CBIT](ERROR): Data Fault in component: WetLabsBB2FL 2012-05-22T14:50:41.803Z,1337698241.803 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035230 psu 2012-05-22T14:50:42.009Z,1337698242.009 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.027620 psu 2012-05-22T14:50:42.107Z,1337698242.107 [WetLabsBB2FL](INFO): Powering down 2012-05-22T14:50:42.155Z,1337698242.155 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2012-05-22T14:50:42.155Z,1337698242.155 [WetLabsBB2FL] No Fault, FailCount= 1 2012-05-22T14:50:42.215Z,1337698242.215 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.043840 psu 2012-05-22T14:50:42.415Z,1337698242.415 [WetLabsBB2FL](INFO): Powering up 2012-05-22T14:50:42.419Z,1337698242.419 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.040100 psu 2012-05-22T14:50:42.619Z,1337698242.619 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010780 psu 2012-05-22T14:50:42.823Z,1337698242.823 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.038520 psu 2012-05-22T14:50:43.027Z,1337698243.027 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012300 psu 2012-05-22T14:50:43.231Z,1337698243.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021020 psu 2012-05-22T14:50:43.435Z,1337698243.435 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023170 psu 2012-05-22T14:50:43.643Z,1337698243.643 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011920 psu 2012-05-22T14:50:43.843Z,1337698243.843 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.005670 psu 2012-05-22T14:50:44.047Z,1337698244.047 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026150 psu 2012-05-22T14:50:44.251Z,1337698244.251 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035050 psu 2012-05-22T14:50:44.455Z,1337698244.455 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016080 psu 2012-05-22T14:50:44.659Z,1337698244.659 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011510 psu 2012-05-22T14:50:44.959Z,1337698244.959 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.019060 psu 2012-05-22T14:50:45.163Z,1337698245.163 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022620 psu 2012-05-22T14:50:45.367Z,1337698245.367 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021470 psu 2012-05-22T14:50:45.572Z,1337698245.572 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014490 psu 2012-05-22T14:50:45.775Z,1337698245.775 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035430 psu 2012-05-22T14:50:45.983Z,1337698245.983 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034530 psu 2012-05-22T14:50:46.179Z,1337698246.179 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.007080 psu 2012-05-22T14:50:46.384Z,1337698246.384 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013110 psu 2012-05-22T14:50:46.591Z,1337698246.591 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034740 psu 2012-05-22T14:50:46.795Z,1337698246.795 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024260 psu 2012-05-22T14:50:46.999Z,1337698246.999 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032520 psu 2012-05-22T14:50:47.203Z,1337698247.203 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017590 psu 2012-05-22T14:50:47.407Z,1337698247.407 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024180 psu 2012-05-22T14:50:47.615Z,1337698247.615 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.053770 psu 2012-05-22T14:50:47.811Z,1337698247.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.007970 psu 2012-05-22T14:50:48.015Z,1337698248.015 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017700 psu 2012-05-22T14:50:48.223Z,1337698248.223 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011650 psu 2012-05-22T14:50:48.427Z,1337698248.427 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032830 psu 2012-05-22T14:50:48.627Z,1337698248.627 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.005480 psu 2012-05-22T14:50:48.831Z,1337698248.831 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013160 psu 2012-05-22T14:50:49.031Z,1337698249.031 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.002150 psu 2012-05-22T14:50:49.235Z,1337698249.235 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010640 psu 2012-05-22T14:50:49.439Z,1337698249.439 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022230 psu 2012-05-22T14:50:49.643Z,1337698249.644 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036090 psu 2012-05-22T14:50:49.851Z,1337698249.851 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.040360 psu 2012-05-22T14:50:50.057Z,1337698250.057 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024100 psu 2012-05-22T14:50:50.355Z,1337698250.355 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032660 psu 2012-05-22T14:50:50.559Z,1337698250.559 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035450 psu 2012-05-22T14:50:50.763Z,1337698250.763 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013900 psu 2012-05-22T14:50:50.968Z,1337698250.968 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.019560 psu 2012-05-22T14:50:51.167Z,1337698251.167 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.001820 psu 2012-05-22T14:50:51.367Z,1337698251.367 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009400 psu 2012-05-22T14:50:51.572Z,1337698251.572 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013070 psu 2012-05-22T14:50:51.775Z,1337698251.775 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012900 psu 2012-05-22T14:50:51.983Z,1337698251.983 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012200 psu 2012-05-22T14:50:52.187Z,1337698252.187 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032680 psu 2012-05-22T14:50:52.391Z,1337698252.391 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011410 psu 2012-05-22T14:50:52.595Z,1337698252.595 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014620 psu 2012-05-22T14:50:52.795Z,1337698252.795 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.008640 psu 2012-05-22T14:50:52.999Z,1337698252.999 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022590 psu 2012-05-22T14:50:53.199Z,1337698253.200 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.003120 psu 2012-05-22T14:50:53.395Z,1337698253.395 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.005090 psu 2012-05-22T14:50:53.600Z,1337698253.600 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024380 psu 2012-05-22T14:50:53.799Z,1337698253.799 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.004080 psu 2012-05-22T14:50:54.004Z,1337698254.004 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033960 psu 2012-05-22T14:50:54.211Z,1337698254.211 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010740 psu 2012-05-22T14:50:54.415Z,1337698254.415 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028190 psu 2012-05-22T14:50:54.615Z,1337698254.615 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010390 psu 2012-05-22T14:50:54.819Z,1337698254.819 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032630 psu 2012-05-22T14:50:55.023Z,1337698255.023 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017230 psu 2012-05-22T14:50:55.227Z,1337698255.227 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016150 psu 2012-05-22T14:50:55.435Z,1337698255.435 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.027570 psu 2012-05-22T14:50:55.639Z,1337698255.639 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034350 psu 2012-05-22T14:50:55.843Z,1337698255.843 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015900 psu 2012-05-22T14:50:56.147Z,1337698256.147 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011210 psu 2012-05-22T14:50:56.343Z,1337698256.343 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.005130 psu 2012-05-22T14:50:56.551Z,1337698256.551 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011380 psu 2012-05-22T14:50:56.747Z,1337698256.747 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.001150 psu 2012-05-22T14:50:56.947Z,1337698256.947 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.008050 psu 2012-05-22T14:50:57.151Z,1337698257.151 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024280 psu 2012-05-22T14:50:57.351Z,1337698257.351 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.002800 psu 2012-05-22T14:50:57.555Z,1337698257.555 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017380 psu 2012-05-22T14:50:57.763Z,1337698257.763 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.018470 psu 2012-05-22T14:50:57.967Z,1337698257.967 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025890 psu 2012-05-22T14:50:58.171Z,1337698258.171 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014820 psu 2012-05-22T14:50:58.372Z,1337698258.372 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.003790 psu 2012-05-22T14:50:58.567Z,1337698258.567 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.006100 psu 2012-05-22T14:50:58.768Z,1337698258.768 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.006420 psu 2012-05-22T14:50:58.967Z,1337698258.967 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.008750 psu 2012-05-22T14:50:59.172Z,1337698259.172 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037740 psu 2012-05-22T14:50:59.379Z,1337698259.379 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017700 psu 2012-05-22T14:50:59.583Z,1337698259.583 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012710 psu 2012-05-22T14:50:59.787Z,1337698259.787 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028560 psu 2012-05-22T14:50:59.987Z,1337698259.987 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009070 psu 2012-05-22T14:51:00.183Z,1337698260.183 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.001150 psu 2012-05-22T14:51:00.387Z,1337698260.388 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021630 psu 2012-05-22T14:51:00.595Z,1337698260.595 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.047130 psu 2012-05-22T14:51:00.799Z,1337698260.799 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023870 psu 2012-05-22T14:51:01.004Z,1337698261.004 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023700 psu 2012-05-22T14:51:01.207Z,1337698261.207 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013840 psu 2012-05-22T14:51:01.408Z,1337698261.408 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.000170 psu 2012-05-22T14:51:01.611Z,1337698261.611 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015380 psu 2012-05-22T14:51:01.816Z,1337698261.816 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013100 psu 2012-05-22T14:51:02.023Z,1337698262.023 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026530 psu 2012-05-22T14:51:02.227Z,1337698262.227 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014490 psu 2012-05-22T14:51:02.433Z,1337698262.433 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.038190 psu 2012-05-22T14:51:02.735Z,1337698262.735 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033330 psu 2012-05-22T14:51:02.939Z,1337698262.939 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026610 psu 2012-05-22T14:51:03.139Z,1337698263.139 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.008770 psu 2012-05-22T14:51:03.347Z,1337698263.347 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014890 psu 2012-05-22T14:51:03.551Z,1337698263.551 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014150 psu 2012-05-22T14:51:03.755Z,1337698263.755 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014210 psu 2012-05-22T14:51:03.955Z,1337698263.955 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.009080 psu 2012-05-22T14:51:04.159Z,1337698264.159 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017520 psu 2012-05-22T14:51:04.364Z,1337698264.364 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015530 psu 2012-05-22T14:51:04.563Z,1337698264.563 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.007060 psu 2012-05-22T14:51:04.763Z,1337698264.763 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009710 psu 2012-05-22T14:51:04.967Z,1337698264.967 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023530 psu 2012-05-22T14:51:05.172Z,1337698265.172 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014120 psu 2012-05-22T14:51:05.371Z,1337698265.371 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.003450 psu 2012-05-22T14:51:05.579Z,1337698265.579 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024710 psu 2012-05-22T14:51:05.775Z,1337698265.775 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.008070 psu 2012-05-22T14:51:05.975Z,1337698265.975 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009320 psu 2012-05-22T14:51:06.179Z,1337698266.179 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034320 psu 2012-05-22T14:51:06.383Z,1337698266.383 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025150 psu 2012-05-22T14:51:06.587Z,1337698266.587 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046800 psu 2012-05-22T14:51:06.795Z,1337698266.795 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037240 psu 2012-05-22T14:51:06.995Z,1337698266.995 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.008800 psu 2012-05-22T14:51:07.195Z,1337698267.195 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.001160 psu 2012-05-22T14:51:07.400Z,1337698267.400 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025490 psu 2012-05-22T14:51:07.603Z,1337698267.603 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012280 psu 2012-05-22T14:51:07.807Z,1337698267.807 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.048300 psu 2012-05-22T14:51:08.007Z,1337698268.007 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.005130 psu 2012-05-22T14:51:08.204Z,1337698268.204 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.007420 psu 2012-05-22T14:51:08.404Z,1337698268.404 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.003760 psu 2012-05-22T14:51:08.607Z,1337698268.607 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039160 psu 2012-05-22T14:51:08.815Z,1337698268.815 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.043470 psu 2012-05-22T14:51:09.019Z,1337698269.019 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.041770 psu 2012-05-22T14:51:09.219Z,1337698269.219 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.003140 psu 2012-05-22T14:51:09.423Z,1337698269.423 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010630 psu 2012-05-22T14:51:09.586Z,1337698269.586 [CommandLine](IMPORTANT): got command run ./Missions/Default.xml 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight] Stopped 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight](INFO): Aggregate::uninitialize tank_weight 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:A.AltitudeEnvelope] Stopped 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:B.DepthEnvelope] Stopped 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:B.DepthEnvelope](DEBUG): Uninitialize. 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:Science] Stopped 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:Science](INFO): Aggregate::uninitialize tank_weight:Science 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:Science:A] Stopped 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:Science:B] Stopped 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:Science:C] Stopped 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:Science:D] Stopped 2012-05-22T14:51:09.586Z,1337698269.586 [tank_weight:D] Stopped 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D](INFO): Aggregate::uninitialize tank_weight:D 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:A.SetSpeed] Stopped 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:A.SetSpeed](DEBUG): Uninitialize. 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:B.Pitch] Stopped 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:BuoyancyLo] Stopped 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:BuoyancyLo](INFO): Aggregate::uninitialize tank_weight:D:BuoyancyLo 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:BuoyancyLo:A.Buoyancy] Stopped 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:BuoyancyLo:B.Wait] Stopped 2012-05-22T14:51:09.587Z,1337698269.587 [tank_weight:D:BuoyancyLo:B.Wait](DEBUG): Uninitialize Wait Component. 2012-05-22T14:51:09.611Z,1337698269.611 [MissionManager](INFO): Loading Mission: ./Missions/Default.xml 2012-05-22T14:51:09.657Z,1337698269.657 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-05-22T14:51:09.662Z,1337698269.662 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-05-22T14:51:09.664Z,1337698269.664 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-05-22T14:51:09.669Z,1337698269.669 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-05-22T14:51:09.672Z,1337698269.672 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-05-22T14:51:09.676Z,1337698269.676 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-05-22T14:51:09.683Z,1337698269.683 [Default:D.SetSpeed](DEBUG): Construct. 2012-05-22T14:51:09.685Z,1337698269.685 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-05-22T14:51:09.688Z,1337698269.688 [Default:F.Wait](DEBUG): Construct Wait. 2012-05-22T14:51:09.692Z,1337698269.692 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-05-22T14:51:09.693Z,1337698269.693 [CommandLine](IMPORTANT): Running ./Missions/Default.xml 2012-05-22T14:51:09.755Z,1337698269.755 [WetLabsBB2FL](INFO): Powering down 2012-05-22T14:51:09.767Z,1337698269.767 [MissionManager](IMPORTANT): Started mission Default 2012-05-22T14:51:09.767Z,1337698269.767 [Default] Running Loop=1 2012-05-22T14:51:09.767Z,1337698269.767 [Default](INFO): Aggregate::initialize Default 2012-05-22T14:51:09.768Z,1337698269.768 [Default:D.SetSpeed] Running Loop=1 2012-05-22T14:51:09.768Z,1337698269.768 [Default:D.SetSpeed](DEBUG): Initialize. 2012-05-22T14:51:09.768Z,1337698269.768 [Default:E.GoToSurface] Running Loop=1 2012-05-22T14:51:09.768Z,1337698269.768 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:51:09.768Z,1337698269.768 [Default:Iridium] Running Loop=1 2012-05-22T14:51:09.768Z,1337698269.768 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-05-22T14:51:09.768Z,1337698269.769 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-05-22T14:51:09.769Z,1337698269.769 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-05-22T14:51:09.769Z,1337698269.769 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-05-22T14:51:09.769Z,1337698269.769 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:51:09.770Z,1337698269.770 [Default:E.GoToSurface] Running Loop=1 2012-05-22T14:51:09.771Z,1337698269.771 [Default:D.SetSpeed] Running Loop=1 2012-05-22T14:51:09.772Z,1337698269.772 [Default:CallIridium] Running Loop=1 2012-05-22T14:51:09.772Z,1337698269.772 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-05-22T14:51:09.772Z,1337698269.772 [Default:CallIridium:A] Running Loop=1 2012-05-22T14:51:09.773Z,1337698269.773 [Default:CallIridium:A] Stopped 2012-05-22T14:51:09.773Z,1337698269.773 [Default:CallIridium:B] Running Loop=1 2012-05-22T14:51:09.773Z,1337698269.773 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-05-22T14:51:09.775Z,1337698269.774 [Default:Iridium:B.GoToSurface] Stopped 2012-05-22T14:51:09.775Z,1337698269.775 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:51:09.775Z,1337698269.775 [Default:Iridium:Read_Iridium] Running Loop=1 2012-05-22T14:51:09.775Z,1337698269.775 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-05-22T14:51:09.776Z,1337698269.776 [Default:GPS] Running Loop=1 2012-05-22T14:51:09.776Z,1337698269.776 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-05-22T14:51:09.776Z,1337698269.776 [Default:GPS:A.SetSpeed] Running Loop=1 2012-05-22T14:51:09.776Z,1337698269.776 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-05-22T14:51:09.776Z,1337698269.776 [Default:GPS:B.GoToSurface] Running Loop=1 2012-05-22T14:51:09.776Z,1337698269.776 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:51:09.778Z,1337698269.778 [Default:GPS:B.GoToSurface] Stopped 2012-05-22T14:51:09.778Z,1337698269.778 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:51:09.778Z,1337698269.778 [Default:GPS:Read_GPS] Running Loop=1 2012-05-22T14:51:09.778Z,1337698269.778 [Default:GPS:A.SetSpeed] Running Loop=1 2012-05-22T14:51:10.116Z,1337698270.116 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-05-22T14:51:10.118Z,1337698270.118 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-05-22T14:54:38.063Z,1337698478.063 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:40.292Z,1337698480.292 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:42.692Z,1337698482.692 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:45.295Z,1337698485.295 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:47.879Z,1337698487.879 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:49.918Z,1337698489.918 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:52.459Z,1337698492.459 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:54.859Z,1337698494.859 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:57.068Z,1337698497.068 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:54:59.647Z,1337698499.647 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:01.647Z,1337698501.647 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:04.251Z,1337698504.251 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:06.432Z,1337698506.432 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:08.832Z,1337698508.832 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:11.232Z,1337698511.232 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:13.632Z,1337698513.632 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:16.255Z,1337698516.256 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:18.651Z,1337698518.651 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:20.872Z,1337698520.872 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:21.221Z,1337698521.221 [NAL9601](FAULT): GPS failed to acquire within timeout. 2012-05-22T14:55:21.221Z,1337698521.221 [NAL9601] Data Fault, FailCount= 2 2012-05-22T14:55:21.221Z,1337698521.221 [NAL9601](ERROR): Data Fault 2012-05-22T14:55:21.243Z,1337698521.243 [CBIT](ERROR): Data Fault in component: NAL9601 2012-05-22T14:55:21.243Z,1337698521.243 [CBIT](CRITICAL): Data Fault in component: NAL9601 2012-05-22T14:55:21.621Z,1337698521.621 [NAL9601](INFO): Powering down 2012-05-22T14:55:22.029Z,1337698522.029 [Default] Stopped 2012-05-22T14:55:22.029Z,1337698522.029 [Default](INFO): Aggregate::uninitialize Default 2012-05-22T14:55:22.029Z,1337698522.029 [Default:GPS] Stopped 2012-05-22T14:55:22.029Z,1337698522.029 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-05-22T14:55:22.029Z,1337698522.029 [Default:GPS:A.SetSpeed] Stopped 2012-05-22T14:55:22.029Z,1337698522.029 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-05-22T14:55:22.030Z,1337698522.030 [Default:GPS:Read_GPS] Stopped 2012-05-22T14:55:22.030Z,1337698522.030 [Default:Iridium] Stopped 2012-05-22T14:55:22.030Z,1337698522.030 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-05-22T14:55:22.030Z,1337698522.030 [Default:Iridium:A.SetSpeed] Stopped 2012-05-22T14:55:22.030Z,1337698522.030 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-05-22T14:55:22.030Z,1337698522.030 [Default:Iridium:Read_Iridium] Stopped 2012-05-22T14:55:22.030Z,1337698522.030 [Default:CallIridium] Stopped 2012-05-22T14:55:22.030Z,1337698522.030 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-05-22T14:55:22.030Z,1337698522.030 [Default:CallIridium:B] Stopped 2012-05-22T14:55:22.030Z,1337698522.030 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-05-22T14:55:22.031Z,1337698522.030 [Default:D.SetSpeed] Stopped 2012-05-22T14:55:22.031Z,1337698522.030 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-05-22T14:55:22.031Z,1337698522.031 [Default:E.GoToSurface] Stopped 2012-05-22T14:55:22.031Z,1337698522.031 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:55:22.432Z,1337698522.432 [MissionManager](IMPORTANT): Started mission Default 2012-05-22T14:55:22.432Z,1337698522.432 [Default] Running Loop=1 2012-05-22T14:55:22.432Z,1337698522.432 [Default](INFO): Aggregate::initialize Default 2012-05-22T14:55:22.432Z,1337698522.432 [Default:D.SetSpeed] Running Loop=1 2012-05-22T14:55:22.432Z,1337698522.432 [Default:D.SetSpeed](DEBUG): Initialize. 2012-05-22T14:55:22.432Z,1337698522.432 [Default:E.GoToSurface] Running Loop=1 2012-05-22T14:55:22.432Z,1337698522.432 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:55:22.433Z,1337698522.433 [Default:Iridium] Running Loop=1 2012-05-22T14:55:22.433Z,1337698522.433 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-05-22T14:55:22.433Z,1337698522.433 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-05-22T14:55:22.433Z,1337698522.433 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-05-22T14:55:22.433Z,1337698522.433 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-05-22T14:55:22.433Z,1337698522.433 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:55:22.435Z,1337698522.435 [Default:CallIridium] Running Loop=1 2012-05-22T14:55:22.435Z,1337698522.435 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-05-22T14:55:22.435Z,1337698522.435 [Default:CallIridium:A] Running Loop=1 2012-05-22T14:55:22.435Z,1337698522.435 [Default:CallIridium:A] Stopped 2012-05-22T14:55:22.436Z,1337698522.436 [Default:CallIridium:B] Running Loop=1 2012-05-22T14:55:22.436Z,1337698522.436 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-05-22T14:55:22.436Z,1337698522.436 [Default:Iridium:B.GoToSurface] Stopped 2012-05-22T14:55:22.437Z,1337698522.437 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:55:22.437Z,1337698522.437 [Default:Iridium:Read_Iridium] Running Loop=1 2012-05-22T14:55:22.437Z,1337698522.437 [Default:GPS] Running Loop=1 2012-05-22T14:55:22.437Z,1337698522.437 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-05-22T14:55:22.437Z,1337698522.437 [Default:GPS:A.SetSpeed] Running Loop=1 2012-05-22T14:55:22.437Z,1337698522.437 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-05-22T14:55:22.437Z,1337698522.437 [Default:GPS:B.GoToSurface] Running Loop=1 2012-05-22T14:55:22.437Z,1337698522.437 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-05-22T14:55:22.439Z,1337698522.439 [Default:GPS:B.GoToSurface] Stopped 2012-05-22T14:55:22.439Z,1337698522.439 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T14:55:22.439Z,1337698522.439 [Default:GPS:Read_GPS] Running Loop=1 2012-05-22T14:55:23.232Z,1337698523.232 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:25.632Z,1337698525.632 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:28.032Z,1337698528.032 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:30.432Z,1337698530.432 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:32.832Z,1337698532.832 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:35.232Z,1337698535.232 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:37.632Z,1337698537.632 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:40.033Z,1337698540.033 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:42.432Z,1337698542.432 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:44.832Z,1337698544.832 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:47.232Z,1337698547.232 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:49.232Z,1337698549.232 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:51.670Z,1337698551.671 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:54.036Z,1337698554.036 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:56.436Z,1337698556.436 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:55:58.832Z,1337698558.832 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:56:01.236Z,1337698561.236 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:56:03.632Z,1337698563.632 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:56:06.032Z,1337698566.032 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:56:08.432Z,1337698568.432 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:56:10.832Z,1337698570.832 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:56:13.232Z,1337698573.232 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-05-22T14:57:21.242Z,1337698641.242 [CBIT](INFO): Clearing failed count for component NAL9601 2012-05-22T14:57:21.242Z,1337698641.242 [NAL9601] No Fault, FailCount= 2 2012-05-22T14:57:21.622Z,1337698641.622 [NAL9601](INFO): Powering up NAL9601 2012-05-22T14:58:27.263Z,1337698707.263 [NAL9601](INFO): NAL9601 initialized 2012-05-22T15:05:41.358Z,1337699141.358 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:05:41.359Z,1337699141.359 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:08:29.121Z,1337699309.121 [NAL9601](FAULT): GPS failed to acquire within timeout. 2012-05-22T15:08:29.121Z,1337699309.121 [NAL9601] Data Fault, FailCount= 1 2012-05-22T15:08:29.121Z,1337699309.121 [NAL9601](ERROR): Data Fault 2012-05-22T15:08:29.145Z,1337699309.145 [CBIT](ERROR): Data Fault in component: NAL9601 2012-05-22T15:08:29.516Z,1337699309.516 [NAL9601](INFO): Powering down 2012-05-22T15:08:29.539Z,1337699309.539 [CBIT](INFO): Clearing failed state for component NAL9601 2012-05-22T15:08:29.539Z,1337699309.539 [NAL9601] No Fault, FailCount= 1 2012-05-22T15:08:34.717Z,1337699314.717 [NAL9601](INFO): Powering up NAL9601 2012-05-22T15:09:40.355Z,1337699380.355 [NAL9601](INFO): NAL9601 initialized 2012-05-22T15:12:46.345Z,1337699566.345 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:12:46.345Z,1337699566.345 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:13:06.833Z,1337699586.833 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:13:06.833Z,1337699586.833 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:14:20.738Z,1337699660.738 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:14:20.738Z,1337699660.738 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:14:53.428Z,1337699693.428 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:14:53.428Z,1337699693.428 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:15:19.558Z,1337699719.557 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:15:19.558Z,1337699719.558 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:19:43.702Z,1337699983.702 [NAL9601](FAULT): GPS failed to acquire within timeout. 2012-05-22T15:19:43.702Z,1337699983.702 [NAL9601] Data Fault, FailCount= 2 2012-05-22T15:19:43.702Z,1337699983.702 [NAL9601](ERROR): Data Fault 2012-05-22T15:19:43.724Z,1337699983.724 [CBIT](ERROR): Data Fault in component: NAL9601 2012-05-22T15:19:43.724Z,1337699983.724 [CBIT](CRITICAL): Data Fault in component: NAL9601 2012-05-22T15:19:44.087Z,1337699984.087 [NAL9601](INFO): Powering down 2012-05-22T15:21:43.723Z,1337700103.723 [CBIT](INFO): Clearing failed count for component NAL9601 2012-05-22T15:21:43.723Z,1337700103.723 [NAL9601] No Fault, FailCount= 2 2012-05-22T15:21:44.084Z,1337700104.084 [NAL9601](INFO): Powering up NAL9601 2012-05-22T15:22:49.727Z,1337700169.727 [NAL9601](INFO): NAL9601 initialized 2012-05-22T15:23:15.604Z,1337700195.605 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:23:15.605Z,1337700195.605 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:23:32.666Z,1337700212.666 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:23:32.666Z,1337700212.666 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:24:06.918Z,1337700246.918 [NAL9601](INFO): SBD MO Status=2, MOMSN=6657, MT Status=2, MTMSN=0 2012-05-22T15:24:06.918Z,1337700246.918 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-05-22T15:30:22.571Z,1337700622.570 [CommandLine](IMPORTANT): got command quit 2012-05-22T15:30:22.618Z,1337700622.618 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2012-05-22T15:30:22.618Z,1337700622.618 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2012-05-22T15:30:23.003Z,1337700623.003 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2012-05-22T15:30:23.019Z,1337700623.019 [CTD_NeilBrown](INFO): Powering down 2012-05-22T15:30:23.019Z,1337700623.019 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler 2012-05-22T15:30:23.133Z,1337700623.133 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator. 2012-05-22T15:30:23.133Z,1337700623.133 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2012-05-22T15:30:23.133Z,1337700623.133 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2012-05-22T15:30:23.315Z,1337700623.316 [controlThread](DEBUG): Uninitializing ControlThread 2012-05-22T15:30:23.316Z,1337700623.316 [AHRS_sp3003D](INFO): Powering down 2012-05-22T15:30:23.404Z,1337700623.404 [DVL_micro](INFO): Powering down 2012-05-22T15:30:23.404Z,1337700623.404 [NAL9601](INFO): Powering down 2012-05-22T15:30:23.405Z,1337700623.405 [WetLabsBB2FL](INFO): Powering down 2012-05-22T15:30:23.405Z,1337700623.405 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-05-22T15:30:23.406Z,1337700623.406 [NavChart](DEBUG): Uninitialize NavChart Derivation. 2012-05-22T15:30:23.407Z,1337700623.407 [Default] Stopped 2012-05-22T15:30:23.407Z,1337700623.407 [Default](INFO): Aggregate::uninitialize Default 2012-05-22T15:30:23.407Z,1337700623.407 [Default:GPS] Stopped 2012-05-22T15:30:23.407Z,1337700623.407 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-05-22T15:30:23.407Z,1337700623.408 [Default:GPS:A.SetSpeed] Stopped 2012-05-22T15:30:23.407Z,1337700623.408 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-05-22T15:30:23.408Z,1337700623.408 [Default:GPS:Read_GPS] Stopped 2012-05-22T15:30:23.408Z,1337700623.408 [Default:Iridium] Stopped 2012-05-22T15:30:23.408Z,1337700623.408 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-05-22T15:30:23.408Z,1337700623.408 [Default:Iridium:A.SetSpeed] Stopped 2012-05-22T15:30:23.408Z,1337700623.408 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-05-22T15:30:23.408Z,1337700623.408 [Default:Iridium:Read_Iridium] Stopped 2012-05-22T15:30:23.408Z,1337700623.408 [Default:CallIridium] Stopped 2012-05-22T15:30:23.408Z,1337700623.408 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-05-22T15:30:23.408Z,1337700623.408 [Default:CallIridium:B] Stopped 2012-05-22T15:30:23.408Z,1337700623.408 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-05-22T15:30:23.408Z,1337700623.408 [Default:D.SetSpeed] Stopped 2012-05-22T15:30:23.408Z,1337700623.408 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-05-22T15:30:23.408Z,1337700623.408 [Default:E.GoToSurface] Stopped 2012-05-22T15:30:23.409Z,1337700623.409 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-05-22T15:30:23.413Z,1337700623.413 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2012-05-22T15:30:23.413Z,1337700623.413 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2012-05-22T15:30:23.413Z,1337700623.413 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2012-05-22T15:30:23.414Z,1337700623.414 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2012-05-22T15:30:23.414Z,1337700623.414 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2012-05-22T15:30:23.414Z,1337700623.414 [BuoyancyServo](INFO): Powering down 2012-05-22T15:30:23.415Z,1337700623.415 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2012-05-22T15:30:23.415Z,1337700623.415 [ElevatorServo](INFO): Powering down 2012-05-22T15:30:23.415Z,1337700623.415 [MassServo](DEBUG): Uninitialize Mass Servo. 2012-05-22T15:30:23.415Z,1337700623.415 [MassServo](INFO): Powering down 2012-05-22T15:30:23.416Z,1337700623.416 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2012-05-22T15:30:23.416Z,1337700623.416 [RudderServo](INFO): Powering down 2012-05-22T15:30:23.416Z,1337700623.416 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2012-05-22T15:30:23.416Z,1337700623.416 [ThrusterServo](INFO): Powering down 2012-05-22T15:30:23.417Z,1337700623.417 [SBIT](DEBUG): Uninitialize SBIT Component. 2012-05-22T15:30:23.417Z,1337700623.417 [IBIT](DEBUG): Uninitialize IBIT Component. 2012-05-22T15:30:23.417Z,1337700623.417 [CBIT](DEBUG): Uninitialize CBIT Component.