2012-09-18T21:39:45.049Z,1348004385.049 [Supervisor](DEBUG): Initializing supervisor. 2012-09-18T21:39:45.052Z,1348004385.052 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2012-09-18T21:39:45.053Z,1348004385.053 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2012-09-18T21:39:45.054Z,1348004385.054 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2012-09-18T21:39:45.057Z,1348004385.057 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2012-09-18T21:39:45.068Z,1348004385.068 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2012-09-18T21:39:45.069Z,1348004385.069 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2012-09-18T21:39:45.070Z,1348004385.070 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread. 2012-09-18T21:39:45.070Z,1348004385.070 [Supervisor](INFO): Looking for Config files in directory: Config/ 2012-09-18T21:39:45.071Z,1348004385.071 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2012-09-18T21:39:45.360Z,1348004385.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2012-09-18T21:39:45.361Z,1348004385.361 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2012-09-18T21:39:45.542Z,1348004385.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2012-09-18T21:39:45.543Z,1348004385.543 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2012-09-18T21:39:45.628Z,1348004385.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2012-09-18T21:39:45.628Z,1348004385.628 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2012-09-18T21:39:45.830Z,1348004385.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2012-09-18T21:39:45.831Z,1348004385.831 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2012-09-18T21:39:45.965Z,1348004385.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2012-09-18T21:39:45.966Z,1348004385.966 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2012-09-18T21:39:46.206Z,1348004386.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2012-09-18T21:39:46.206Z,1348004386.206 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2012-09-18T21:39:46.377Z,1348004386.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2012-09-18T21:39:46.377Z,1348004386.377 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2012-09-18T21:39:46.640Z,1348004386.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2012-09-18T21:39:46.641Z,1348004386.641 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2012-09-18T21:39:46.739Z,1348004386.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2012-09-18T21:39:46.740Z,1348004386.740 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2012-09-18T21:39:47.142Z,1348004387.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2012-09-18T21:39:47.142Z,1348004387.142 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2012-09-18T21:39:47.255Z,1348004387.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2012-09-18T21:39:47.256Z,1348004387.256 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2012-09-18T21:39:47.341Z,1348004387.341 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2012-09-18T21:39:47.342Z,1348004387.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2012-09-18T21:39:47.444Z,1348004387.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2012-09-18T21:39:47.573Z,1348004387.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2012-09-18T21:39:47.659Z,1348004387.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2012-09-18T21:39:47.760Z,1348004387.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2012-09-18T21:39:47.861Z,1348004387.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2012-09-18T21:39:47.979Z,1348004387.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2012-09-18T21:39:48.069Z,1348004388.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2012-09-18T21:39:48.160Z,1348004388.160 [Supervisor](IMPORTANT): Reading configuration overrides from Data/config.db 2012-09-18T21:39:48.169Z,1348004388.169 [ComponentRegistry](DEBUG): Loaded Config Component "configDb 2012-09-18T21:39:48.200Z,1348004388.200 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2012-09-18T21:39:48.338Z,1348004388.339 [InternalSim] Loaded 2012-09-18T21:39:48.339Z,1348004388.339 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2012-09-18T21:39:48.340Z,1348004388.340 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2012-09-18T21:39:48.340Z,1348004388.340 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2012-09-18T21:39:48.442Z,1348004388.442 [SBIT](DEBUG): Construct Startup Built In Test. 2012-09-18T21:39:48.456Z,1348004388.456 [SBIT] Loaded 2012-09-18T21:39:48.456Z,1348004388.456 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2012-09-18T21:39:48.457Z,1348004388.457 [IBIT](DEBUG): Construct Initiated Built In Test. 2012-09-18T21:39:48.485Z,1348004388.485 [IBIT] Loaded 2012-09-18T21:39:48.486Z,1348004388.486 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2012-09-18T21:39:48.489Z,1348004388.489 [CBIT](DEBUG): Construct CBIT Built In Test. 2012-09-18T21:39:48.607Z,1348004388.607 [CBIT] Loaded 2012-09-18T21:39:48.607Z,1348004388.607 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2012-09-18T21:39:48.608Z,1348004388.608 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2012-09-18T21:39:48.608Z,1348004388.608 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2012-09-18T21:39:48.749Z,1348004388.749 [BuoyancyServo] Loaded 2012-09-18T21:39:48.749Z,1348004388.749 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2012-09-18T21:39:48.762Z,1348004388.762 [RudderServo] Loaded 2012-09-18T21:39:48.763Z,1348004388.763 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2012-09-18T21:39:48.775Z,1348004388.774 [ThrusterServo] Loaded 2012-09-18T21:39:48.775Z,1348004388.775 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2012-09-18T21:39:48.775Z,1348004388.775 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2012-09-18T21:39:48.776Z,1348004388.776 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2012-09-18T21:39:48.793Z,1348004388.793 [DepthRateCalculator] Loaded 2012-09-18T21:39:48.793Z,1348004388.793 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2012-09-18T21:39:51.308Z,1348004391.307 [HFRadarModelCalc] Loaded 2012-09-18T21:39:51.308Z,1348004391.308 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2012-09-18T21:39:51.324Z,1348004391.324 [NavChart] Loaded 2012-09-18T21:39:51.324Z,1348004391.324 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2012-09-18T21:39:51.330Z,1348004391.330 [PitchRateCalculator] Loaded 2012-09-18T21:39:51.330Z,1348004391.330 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2012-09-18T21:39:51.336Z,1348004391.336 [SpeedCalculator] Loaded 2012-09-18T21:39:51.337Z,1348004391.337 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2012-09-18T21:39:51.352Z,1348004391.352 [TempGradientCalculator] Loaded 2012-09-18T21:39:51.352Z,1348004391.352 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2012-09-18T21:39:51.358Z,1348004391.358 [YawRateCalculator] Loaded 2012-09-18T21:39:51.359Z,1348004391.359 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2012-09-18T21:39:51.379Z,1348004391.379 [Navigation] Loaded 2012-09-18T21:39:51.379Z,1348004391.379 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2012-09-18T21:39:51.380Z,1348004391.379 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2012-09-18T21:39:51.380Z,1348004391.380 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2012-09-18T21:39:51.603Z,1348004391.603 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2012-09-18T21:39:51.604Z,1348004391.604 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2012-09-18T21:39:51.625Z,1348004391.625 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2012-09-18T21:39:51.625Z,1348004391.625 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2012-09-18T21:39:51.666Z,1348004391.666 [VerticalControl](DEBUG): Construct VerticalControl. 2012-09-18T21:39:51.712Z,1348004391.712 [VerticalControl] Loaded 2012-09-18T21:39:51.712Z,1348004391.712 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2012-09-18T21:39:51.713Z,1348004391.713 [HorizontalControl](DEBUG): Construct HorizontalControl. 2012-09-18T21:39:51.735Z,1348004391.735 [HorizontalControl] Loaded 2012-09-18T21:39:51.735Z,1348004391.735 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2012-09-18T21:39:51.736Z,1348004391.736 [SpeedControl](DEBUG): Construct SpeedControl. 2012-09-18T21:39:51.738Z,1348004391.738 [SpeedControl] Loaded 2012-09-18T21:39:51.738Z,1348004391.738 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2012-09-18T21:39:51.739Z,1348004391.739 [LoopControl](DEBUG): Construct LoopControl. 2012-09-18T21:39:51.739Z,1348004391.740 [LoopControl] Loaded 2012-09-18T21:39:51.740Z,1348004391.740 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2012-09-18T21:39:51.740Z,1348004391.740 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2012-09-18T21:39:51.741Z,1348004391.741 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2012-09-18T21:39:51.746Z,1348004391.746 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2012-09-18T21:39:51.751Z,1348004391.751 [AsyncPiEstimator] Loaded 2012-09-18T21:39:51.751Z,1348004391.751 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2012-09-18T21:39:51.752Z,1348004391.752 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4060A4E0 2012-09-18T21:39:51.753Z,1348004391.753 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2012-09-18T21:39:51.754Z,1348004391.754 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2012-09-18T21:39:51.874Z,1348004391.875 [AHRS_sp3003D] Loaded 2012-09-18T21:39:51.875Z,1348004391.875 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2012-09-18T21:39:51.888Z,1348004391.888 [Depth_Keller] Loaded 2012-09-18T21:39:51.889Z,1348004391.889 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2012-09-18T21:39:51.894Z,1348004391.894 [DropWeight] Loaded 2012-09-18T21:39:51.894Z,1348004391.894 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2012-09-18T21:39:52.010Z,1348004392.010 [DVL_micro] Loaded 2012-09-18T21:39:52.010Z,1348004392.010 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2012-09-18T21:39:52.012Z,1348004392.012 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 4068A4E0 2012-09-18T21:39:52.093Z,1348004392.093 [NAL9602] Loaded 2012-09-18T21:39:52.093Z,1348004392.093 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2012-09-18T21:39:52.143Z,1348004392.143 [Onboard] Loaded 2012-09-18T21:39:52.143Z,1348004392.143 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2012-09-18T21:39:52.150Z,1348004392.150 [Radio_Freewave] Loaded 2012-09-18T21:39:52.150Z,1348004392.151 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2012-09-18T21:39:52.151Z,1348004392.151 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2012-09-18T21:39:52.152Z,1348004392.152 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2012-09-18T21:39:52.221Z,1348004392.221 [CTD_NeilBrown] Loaded 2012-09-18T21:39:52.221Z,1348004392.221 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2012-09-18T21:39:52.223Z,1348004392.223 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406E24E0 2012-09-18T21:39:52.233Z,1348004392.233 [PAR_Licor] Loaded 2012-09-18T21:39:52.234Z,1348004392.234 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2012-09-18T21:39:52.250Z,1348004392.250 [Turbulence_NPS] Loaded 2012-09-18T21:39:52.251Z,1348004392.251 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread. 2012-09-18T21:39:52.252Z,1348004392.252 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 407124E0 2012-09-18T21:39:52.283Z,1348004392.283 [WetLabsBB2FL] Loaded 2012-09-18T21:39:52.283Z,1348004392.283 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2012-09-18T21:39:52.284Z,1348004392.284 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407424E0 2012-09-18T21:39:52.285Z,1348004392.285 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2012-09-18T21:39:52.287Z,1348004392.287 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2012-09-18T21:39:52.288Z,1348004392.288 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2012-09-18T21:39:52.292Z,1348004392.293 [Supervisor](DEBUG): Running supervisor. 2012-09-18T21:39:52.296Z,1348004392.296 [controlThread](DEBUG): Initializing ControlThread 2012-09-18T21:39:52.297Z,1348004392.297 [InternalSim](DEBUG): InternalSim initializing... 2012-09-18T21:39:52.331Z,1348004392.331 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2012-09-18T21:39:52.341Z,1348004392.341 [SBIT](INFO): Initialize SBIT Component. 2012-09-18T21:39:52.341Z,1348004392.341 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 9861 2012-09-18T21:39:52.342Z,1348004392.342 [IBIT](INFO): Initialize IBIT Component. 2012-09-18T21:39:52.343Z,1348004392.343 [CBIT](DEBUG): Initialize CBIT Component. 2012-09-18T21:39:52.343Z,1348004392.343 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2012-09-18T21:39:52.353Z,1348004392.353 [DVL_micro](INFO): Initializing 2012-09-18T21:39:52.353Z,1348004392.353 [DVL_micro](INFO): start:Powering up 2012-09-18T21:39:52.367Z,1348004392.367 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-09-18T21:39:52.368Z,1348004392.368 [NavChart](DEBUG): Initialize NavChart Derivation. 2012-09-18T21:39:52.368Z,1348004392.368 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2012-09-18T21:39:52.368Z,1348004392.368 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2012-09-18T21:39:52.369Z,1348004392.369 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2012-09-18T21:39:52.370Z,1348004392.370 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2012-09-18T21:39:52.371Z,1348004392.371 [Navigation](DEBUG): Initializing Navigation. 2012-09-18T21:39:52.371Z,1348004392.371 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2012-09-18T21:39:52.373Z,1348004392.373 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2012-09-18T21:39:52.374Z,1348004392.374 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2012-09-18T21:39:52.374Z,1348004392.374 [LoopControl](DEBUG): Initialize LoopControlComponent. 2012-09-18T21:39:52.380Z,1348004392.380 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2012-09-18T21:39:52.388Z,1348004392.388 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-09-18T21:39:52.408Z,1348004392.408 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2012-09-18T21:39:52.415Z,1348004392.415 [WetLabsBB2FL](INFO): Powering down 2012-09-18T21:39:52.439Z,1348004392.439 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-09-18T21:39:52.452Z,1348004392.452 [MissionManager](DEBUG): 2012-09-18T21:39:52.452Z,1348004392.452 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2012-09-18T21:39:52.504Z,1348004392.504 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-09-18T21:39:52.507Z,1348004392.507 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-09-18T21:39:52.510Z,1348004392.510 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-09-18T21:39:52.519Z,1348004392.519 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-09-18T21:39:52.523Z,1348004392.523 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-09-18T21:39:52.530Z,1348004392.530 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-09-18T21:39:52.540Z,1348004392.540 [Default:D.SetSpeed](DEBUG): Construct. 2012-09-18T21:39:52.543Z,1348004392.543 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-09-18T21:39:52.549Z,1348004392.549 [Default:F.Wait](DEBUG): Construct Wait. 2012-09-18T21:39:52.553Z,1348004392.553 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-09-18T21:39:52.557Z,1348004392.557 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger, 2012-09-18T21:39:52.579Z,1348004392.579 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-09-18T21:39:52.693Z,1348004392.693 [Radio_Freewave](INFO): Powering up 2012-09-18T21:39:52.700Z,1348004392.700 [PAR_Licor](ERROR): PAR reading out of range: 6665.399414 uMol/s/m2 2012-09-18T21:39:52.781Z,1348004392.781 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2012-09-18T21:39:52.783Z,1348004392.783 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2012-09-18T21:39:52.789Z,1348004392.789 [RudderServo](DEBUG): Initializing EZServoServo. 2012-09-18T21:39:52.790Z,1348004392.790 [RudderServo](DEBUG): Initializing RudderServo. 2012-09-18T21:39:52.797Z,1348004392.797 [ThrusterServo](DEBUG): Initializing EZServoServo. 2012-09-18T21:39:52.798Z,1348004392.798 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2012-09-18T21:39:53.914Z,1348004393.914 [DVL_micro](INFO): Querying output modes 2012-09-18T21:39:53.915Z,1348004393.915 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-09-18T21:39:53.919Z,1348004393.918 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-09-18T21:39:53.919Z,1348004393.919 [DVL_micro](INFO): NQ1 output enabled 2012-09-18T21:39:53.919Z,1348004393.919 [DVL_micro](INFO): RSSI output enabled 2012-09-18T21:39:53.919Z,1348004393.919 [DVL_micro](INFO): ADCP output enabled 2012-09-18T21:39:53.919Z,1348004393.919 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-09-18T21:39:53.927Z,1348004393.927 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-09-18T21:39:53.931Z,1348004393.931 [DVL_micro](INFO): pause:Powering down 2012-09-18T21:39:57.332Z,1348004397.332 [NAL9602](INFO): Powering up NAL9602 2012-09-18T21:40:07.772Z,1348004407.772 [SBIT](IMPORTANT): Beginning Startup BIT 2012-09-18T21:40:07.775Z,1348004407.775 [CBIT](IMPORTANT): Beginning GF scan 2012-09-18T21:40:08.163Z,1348004408.163 [NAL9602](INFO): NAL9602 initialized 2012-09-18T21:40:16.519Z,1348004416.519 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.900002 degree 2012-09-18T21:40:16.521Z,1348004416.522 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2012-09-18T21:40:16.522Z,1348004416.522 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.930000 degree 2012-09-18T21:40:16.524Z,1348004416.524 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2012-09-18T21:40:16.524Z,1348004416.524 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-09-18T21:40:16.623Z,1348004416.623 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger, 2012-09-18T21:40:20.981Z,1348004420.981 [SBIT](FAULT): Mass: EXPECTED:0.004800 ACTUAL:-0.001040 2012-09-18T21:40:24.936Z,1348004424.936 [NAL9602](INFO): Powering down 2012-09-18T21:40:34.185Z,1348004434.185 [CBIT](FAULT): Low side GF detected mA: CHAN 5 (24V): 0.391695 CHAN 4 (Batt): 0.391695 CHAN 2 (12V): 0.391695 CHAN 1 (5V): 0.391695 CHAN 0 (3.3V): 0.335452 OPEN: 0.000670 Full Scale Calc: 0.392 2012-09-18T21:41:01.380Z,1348004461.380 [SBIT](CRITICAL): SBIT FAILED 2012-09-18T21:41:01.750Z,1348004461.750 [MissionManager](IMPORTANT): Started mission Startup 2012-09-18T21:41:01.750Z,1348004461.750 [Startup] Running Loop=1 2012-09-18T21:41:01.750Z,1348004461.750 [Startup](INFO): Aggregate::initialize Startup 2012-09-18T21:41:01.750Z,1348004461.750 [Startup:A.GoToSurface] Running Loop=1 2012-09-18T21:41:01.750Z,1348004461.750 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:41:01.756Z,1348004461.756 [Startup:StartupSatComms] Running Loop=1 2012-09-18T21:41:01.756Z,1348004461.756 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2012-09-18T21:41:01.756Z,1348004461.756 [Startup:StartupSatComms:A] Running Loop=1 2012-09-18T21:41:02.152Z,1348004462.152 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-09-18T21:41:02.154Z,1348004462.154 [Startup:StartupSatComms:A] Stopped 2012-09-18T21:41:02.154Z,1348004462.154 [Startup:StartupSatComms:B] Running Loop=1 2012-09-18T21:41:02.550Z,1348004462.550 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-09-18T21:41:02.935Z,1348004462.935 [NAL9602](INFO): Powering up 2012-09-18T21:41:08.687Z,1348004468.687 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.900002 degree 2012-09-18T21:41:08.688Z,1348004468.688 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.930000 degree 2012-09-18T21:41:08.688Z,1348004468.688 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-09-18T21:41:08.951Z,1348004468.951 [Startup:StartupSatComms:B] Stopped 2012-09-18T21:41:08.951Z,1348004468.951 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2012-09-18T21:41:08.952Z,1348004468.952 [Startup:StartupSatComms] Stopped 2012-09-18T21:41:08.952Z,1348004468.952 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2012-09-18T21:41:08.953Z,1348004468.953 [Startup](INFO): Completed Startup 2012-09-18T21:41:08.953Z,1348004468.953 [Startup] Stopped 2012-09-18T21:41:08.953Z,1348004468.953 [Startup](INFO): Aggregate::uninitialize Startup 2012-09-18T21:41:08.953Z,1348004468.953 [Startup:A.GoToSurface] Stopped 2012-09-18T21:41:08.953Z,1348004468.953 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:41:09.353Z,1348004469.353 [MissionManager](IMPORTANT): Started mission Default 2012-09-18T21:41:09.353Z,1348004469.353 [Default] Running Loop=1 2012-09-18T21:41:09.353Z,1348004469.353 [Default](INFO): Aggregate::initialize Default 2012-09-18T21:41:09.353Z,1348004469.353 [Default:D.SetSpeed] Running Loop=1 2012-09-18T21:41:09.353Z,1348004469.353 [Default:D.SetSpeed](DEBUG): Initialize. 2012-09-18T21:41:09.353Z,1348004469.353 [Default:E.GoToSurface] Running Loop=1 2012-09-18T21:41:09.354Z,1348004469.353 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:41:09.354Z,1348004469.354 [Default:Iridium] Running Loop=1 2012-09-18T21:41:09.354Z,1348004469.354 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-09-18T21:41:09.354Z,1348004469.354 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-09-18T21:41:09.354Z,1348004469.354 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-09-18T21:41:09.354Z,1348004469.354 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-09-18T21:41:09.354Z,1348004469.355 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:41:09.355Z,1348004469.355 [Default:E.GoToSurface] Running Loop=1 2012-09-18T21:41:09.360Z,1348004469.360 [Default:D.SetSpeed] Running Loop=1 2012-09-18T21:41:09.371Z,1348004469.371 [Default:Iridium:B.GoToSurface] Stopped 2012-09-18T21:41:09.371Z,1348004469.371 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:41:09.371Z,1348004469.371 [Default:Iridium:Read_Iridium] Running Loop=1 2012-09-18T21:41:09.371Z,1348004469.372 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-09-18T21:41:09.377Z,1348004469.377 [Default:GPS] Running Loop=1 2012-09-18T21:41:09.377Z,1348004469.377 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-09-18T21:41:09.377Z,1348004469.377 [Default:GPS:A.SetSpeed] Running Loop=1 2012-09-18T21:41:09.377Z,1348004469.377 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-09-18T21:41:09.377Z,1348004469.378 [Default:GPS:B.GoToSurface] Running Loop=1 2012-09-18T21:41:09.378Z,1348004469.378 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:41:09.383Z,1348004469.383 [Default:GPS:B.GoToSurface] Stopped 2012-09-18T21:41:09.383Z,1348004469.383 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:41:09.384Z,1348004469.384 [Default:GPS:Read_GPS] Running Loop=1 2012-09-18T21:41:09.384Z,1348004469.384 [Default:GPS:A.SetSpeed] Running Loop=1 2012-09-18T21:41:09.752Z,1348004469.752 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-09-18T21:41:09.755Z,1348004469.755 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-09-18T21:41:09.756Z,1348004469.756 [Default:GPS:Read_GPS] Stopped 2012-09-18T21:41:09.756Z,1348004469.756 [Default:GPS:D] Running Loop=1 2012-09-18T21:41:10.154Z,1348004470.154 [Default:GPS:D] Stopped 2012-09-18T21:41:10.154Z,1348004470.154 [Default:GPS](INFO): Completed Default:GPS 2012-09-18T21:41:10.154Z,1348004470.154 [Default:GPS] Stopped 2012-09-18T21:41:10.154Z,1348004470.154 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-09-18T21:41:10.154Z,1348004470.154 [Default:GPS:A.SetSpeed] Stopped 2012-09-18T21:41:10.154Z,1348004470.155 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-09-18T21:41:13.767Z,1348004473.767 [NAL9602](INFO): NAL9602 initialized 2012-09-18T21:41:33.257Z,1348004493.257 [NAL9602](INFO): SBD MO Status=2, MOMSN=1285, MT Status=2, MTMSN=0 2012-09-18T21:41:33.258Z,1348004493.258 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2012-09-18T21:41:33.299Z,1348004493.299 [CommandLine](IMPORTANT): got command show variable turb 2012-09-18T21:41:33.309Z,1348004493.309 [CommandLine](INFO): Config/vehicle.Turbulence_NPS_card (none) 2012-09-18T21:41:33.309Z,1348004493.309 [CommandLine](INFO): Config/vehicle.Turbulence_NPS_uart (none) 2012-09-18T21:41:33.310Z,1348004493.309 [CommandLine](INFO): Config/vehicle.Turbulence_NPS_baud (bit_per_second) 2012-09-18T21:41:33.337Z,1348004493.337 [CommandLine](INFO): Config/Science.Turbulence_NPS_enable (bool) 2012-09-18T21:41:33.338Z,1348004493.338 [CommandLine](INFO): Config/Science.Turbulence_NPS_useHardware (bool) 2012-09-18T21:41:33.342Z,1348004493.342 [CommandLine](INFO): Config/Science.Turbulence_NPS_power (watt) 2012-09-18T21:41:33.391Z,1348004493.391 [CommandLine](INFO): Turbulence_NPS.MicroTemp1 (none) 2012-09-18T21:41:33.391Z,1348004493.391 [CommandLine](INFO): Turbulence_NPS.MicroCond (none) 2012-09-18T21:41:33.392Z,1348004493.392 [CommandLine](INFO): Turbulence_NPS.MicroTemp2 (none) 2012-09-18T21:41:33.392Z,1348004493.392 [CommandLine](INFO): Turbulence_NPS.NumTempCondSamples (count) 2012-09-18T21:41:33.392Z,1348004493.392 [CommandLine](INFO): Turbulence_NPS.ACMPath (none) 2012-09-18T21:41:33.393Z,1348004493.393 [CommandLine](INFO): Turbulence_NPS.platform_roll_angle (radian) 2012-09-18T21:41:33.393Z,1348004493.393 [CommandLine](INFO): Turbulence_NPS.platform_pitch_angle (radian) 2012-09-18T21:41:33.393Z,1348004493.393 [CommandLine](INFO): Turbulence_NPS.platform_orientation (radian) 2012-09-18T21:41:33.394Z,1348004493.394 [CommandLine](INFO): Turbulence_NPS.DeltaXVelocity (none) 2012-09-18T21:41:33.398Z,1348004493.398 [CommandLine](INFO): Turbulence_NPS.DeltaYVelocity (none) 2012-09-18T21:41:33.399Z,1348004493.399 [CommandLine](INFO): Turbulence_NPS.DeltaZVelocity (none) 2012-09-18T21:41:33.399Z,1348004493.399 [CommandLine](INFO): Turbulence_NPS.FwdSigStrength (none) 2012-09-18T21:41:33.400Z,1348004493.400 [CommandLine](INFO): Turbulence_NPS.packetNum (count) 2012-09-18T21:41:33.401Z,1348004493.401 [CommandLine](INFO): Turbulence_NPS.durationOfLastRun (second) 2012-09-18T21:41:33.401Z,1348004493.401 [CommandLine](INFO): Turbulence_NPS.component_voltage (volt) 2012-09-18T21:41:33.402Z,1348004493.402 [CommandLine](INFO): Turbulence_NPS.component_avgVoltage (volt) 2012-09-18T21:41:33.402Z,1348004493.402 [CommandLine](INFO): Turbulence_NPS.component_current (milliampere) 2012-09-18T21:41:33.407Z,1348004493.407 [CommandLine](INFO): Turbulence_NPS.component_avgCurrent (milliampere) 2012-09-18T21:41:45.375Z,1348004505.375 [CommandLine](IMPORTANT): got command report touch Turbulence_NPS.component_voltage 2012-09-18T21:41:55.957Z,1348004515.957 [NAL9602](INFO): SBD MO Status=2, MOMSN=1285, MT Status=2, MTMSN=0 2012-09-18T21:41:55.957Z,1348004515.957 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2012-09-18T21:41:59.691Z,1348004519.691 [CommandLine](IMPORTANT): got command load ./Missions/Science/science_to_67_70.xml 2012-09-18T21:41:59.691Z,1348004519.691 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml 2012-09-18T21:41:59.774Z,1348004519.774 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m 2012-09-18T21:41:59.777Z,1348004519.777 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m 2012-09-18T21:41:59.783Z,1348004519.783 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m 2012-09-18T21:41:59.788Z,1348004519.788 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m 2012-09-18T21:41:59.791Z,1348004519.791 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m 2012-09-18T21:41:59.794Z,1348004519.794 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg 2012-09-18T21:41:59.797Z,1348004519.797 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg 2012-09-18T21:41:59.800Z,1348004519.800 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg 2012-09-18T21:41:59.803Z,1348004519.803 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg 2012-09-18T21:41:59.806Z,1348004519.806 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min 2012-09-18T21:41:59.809Z,1348004519.809 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s 2012-09-18T21:41:59.812Z,1348004519.812 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h 2012-09-18T21:41:59.816Z,1348004519.816 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a 2012-09-18T21:41:59.819Z,1348004519.819 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-09-18T21:41:59.826Z,1348004519.826 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait. 2012-09-18T21:41:59.827Z,1348004519.827 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-09-18T21:41:59.837Z,1348004519.838 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-09-18T21:41:59.845Z,1348004519.845 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait. 2012-09-18T21:41:59.846Z,1348004519.846 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-09-18T21:41:59.863Z,1348004519.863 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-09-18T21:41:59.866Z,1348004519.866 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-09-18T21:41:59.869Z,1348004519.869 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s 2012-09-18T21:41:59.872Z,1348004519.872 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min 2012-09-18T21:41:59.873Z,1348004519.873 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-09-18T21:41:59.884Z,1348004519.884 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg 2012-09-18T21:41:59.890Z,1348004519.890 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2012-09-18T21:41:59.997Z,1348004519.997 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool 2012-09-18T21:42:00.004Z,1348004520.003 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool 2012-09-18T21:42:00.006Z,1348004520.006 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool 2012-09-18T21:42:00.009Z,1348004520.009 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool 2012-09-18T21:42:00.015Z,1348004520.016 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool 2012-09-18T21:42:00.019Z,1348004520.019 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleAanderaaO2 = 0.000000 n/a 2012-09-18T21:42:00.022Z,1348004520.022 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleNeilBrown = 1.000000 n/a 2012-09-18T21:42:00.025Z,1348004520.025 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleTurbulence_NPS = 1.000000 n/a 2012-09-18T21:42:00.033Z,1348004520.033 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleWetLabsBB2FL = 1.000000 n/a 2012-09-18T21:42:00.036Z,1348004520.036 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a 2012-09-18T21:42:00.039Z,1348004520.039 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l 2012-09-18T21:42:00.047Z,1348004520.046 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m 2012-09-18T21:42:00.050Z,1348004520.050 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg 2012-09-18T21:42:00.053Z,1348004520.053 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg 2012-09-18T21:42:00.056Z,1348004520.056 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l 2012-09-18T21:42:00.067Z,1348004520.067 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m 2012-09-18T21:42:00.075Z,1348004520.075 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg 2012-09-18T21:42:00.078Z,1348004520.078 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg 2012-09-18T21:42:00.106Z,1348004520.106 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-09-18T21:42:00.123Z,1348004520.123 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-09-18T21:42:00.141Z,1348004520.141 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool 2012-09-18T21:42:00.146Z,1348004520.146 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool 2012-09-18T21:42:00.148Z,1348004520.148 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy. 2012-09-18T21:42:00.156Z,1348004520.156 [science_to_67_70:H.SetSpeed](DEBUG): Construct. 2012-09-18T21:42:00.163Z,1348004520.163 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-09-18T21:42:00.181Z,1348004520.181 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo. 2012-09-18T21:42:00.198Z,1348004520.198 [science_to_67_70:Wpt.Point](DEBUG): Construct. 2012-09-18T21:42:00.244Z,1348004520.244 [MissionManager](DEBUG): Vehicle yo-yo's to the specified waypoint, with science turned on. Minimum height above the sea floor for the entire mission. 10.0 Maximum depth for the entire mission. 105.0 Minimum offshore distance for the entire mission. 2000.0 Minimum depth while performing the YoYo behavior. 3.0 Maximum depth while performing the YoYo behavior. 100.0 Vehicle up pitch while performing the YoYo behavior. 20.0 Vehicle down pitch while performing the YoYo behavior. -15.0 Latitude of waypoint. 36.120 Longitude of waypoint. -123.486 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications, if the vehicle has a positive pitch. If the vehicle has a negative pitch, it will wait up to 10 minutes more to begin ascent. 120 Speed while performing the YoYo behavior. 1 Maximum length of mission 36 Buoyancy bladder position while performing the YoYo behavior. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. 0 Safety behavior that keeps vehicle off sea floor Safety behavior that keeps vehicle from going too deep 0 Safety behavior that keeps vehicle away from the shore 20 0 10 If we're executing here or below, lets' get science data! 2012-09-18T21:42:00.245Z,1348004520.245 [CommandLine](IMPORTANT): Loaded ./Missions/Science/science_to_67_70.xml 2012-09-18T21:42:00.245Z,1348004520.245 [CommandLine](IMPORTANT): got command set 2012-09-18T21:42:00.245Z,1348004520.245 [CommandLine](FAULT): Incomplete syntax. Try: help set 2012-09-18T21:42:04.291Z,1348004524.291 [CommandLine](IMPORTANT): got command load ./Missions/Science/science_to_67_70.xml 2012-09-18T21:42:04.305Z,1348004524.305 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml 2012-09-18T21:42:04.401Z,1348004524.401 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m 2012-09-18T21:42:04.403Z,1348004524.403 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m 2012-09-18T21:42:04.405Z,1348004524.405 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m 2012-09-18T21:42:04.424Z,1348004524.424 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m 2012-09-18T21:42:04.426Z,1348004524.426 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m 2012-09-18T21:42:04.428Z,1348004524.428 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg 2012-09-18T21:42:04.431Z,1348004524.431 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg 2012-09-18T21:42:04.433Z,1348004524.433 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg 2012-09-18T21:42:04.451Z,1348004524.451 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg 2012-09-18T21:42:04.454Z,1348004524.454 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min 2012-09-18T21:42:04.457Z,1348004524.457 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s 2012-09-18T21:42:04.459Z,1348004524.459 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h 2012-09-18T21:42:04.462Z,1348004524.462 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a 2012-09-18T21:42:04.464Z,1348004524.464 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-09-18T21:42:04.486Z,1348004524.486 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait. 2012-09-18T21:42:04.487Z,1348004524.487 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-09-18T21:42:04.494Z,1348004524.494 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-09-18T21:42:04.511Z,1348004524.511 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait. 2012-09-18T21:42:04.512Z,1348004524.511 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-09-18T21:42:04.541Z,1348004524.542 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-09-18T21:42:04.549Z,1348004524.549 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-09-18T21:42:04.552Z,1348004524.552 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s 2012-09-18T21:42:04.554Z,1348004524.554 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min 2012-09-18T21:42:04.555Z,1348004524.555 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-09-18T21:42:04.564Z,1348004524.564 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg 2012-09-18T21:42:04.569Z,1348004524.569 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2012-09-18T21:42:04.624Z,1348004524.624 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool 2012-09-18T21:42:04.626Z,1348004524.626 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool 2012-09-18T21:42:04.628Z,1348004524.628 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool 2012-09-18T21:42:04.631Z,1348004524.631 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool 2012-09-18T21:42:04.633Z,1348004524.633 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool 2012-09-18T21:42:04.635Z,1348004524.635 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleAanderaaO2 = 0.000000 n/a 2012-09-18T21:42:04.638Z,1348004524.638 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleNeilBrown = 1.000000 n/a 2012-09-18T21:42:04.640Z,1348004524.640 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleTurbulence_NPS = 1.000000 n/a 2012-09-18T21:42:04.642Z,1348004524.642 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleWetLabsBB2FL = 1.000000 n/a 2012-09-18T21:42:04.645Z,1348004524.645 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a 2012-09-18T21:42:04.647Z,1348004524.647 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l 2012-09-18T21:42:04.650Z,1348004524.650 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m 2012-09-18T21:42:04.652Z,1348004524.652 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg 2012-09-18T21:42:04.655Z,1348004524.655 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg 2012-09-18T21:42:04.657Z,1348004524.657 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l 2012-09-18T21:42:04.686Z,1348004524.686 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m 2012-09-18T21:42:04.688Z,1348004524.688 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg 2012-09-18T21:42:04.691Z,1348004524.691 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg 2012-09-18T21:42:04.709Z,1348004524.709 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-09-18T21:42:04.721Z,1348004524.721 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-09-18T21:42:04.728Z,1348004524.727 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool 2012-09-18T21:42:04.729Z,1348004524.729 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool 2012-09-18T21:42:04.730Z,1348004524.730 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy. 2012-09-18T21:42:04.741Z,1348004524.741 [science_to_67_70:H.SetSpeed](DEBUG): Construct. 2012-09-18T21:42:04.743Z,1348004524.743 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-09-18T21:42:04.749Z,1348004524.749 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo. 2012-09-18T21:42:04.759Z,1348004524.759 [science_to_67_70:Wpt.Point](DEBUG): Construct. 2012-09-18T21:42:04.794Z,1348004524.794 [MissionManager](DEBUG): Vehicle yo-yo's to the specified waypoint, with science turned on. Minimum height above the sea floor for the entire mission. 10.0 Maximum depth for the entire mission. 105.0 Minimum offshore distance for the entire mission. 2000.0 Minimum depth while performing the YoYo behavior. 3.0 Maximum depth while performing the YoYo behavior. 100.0 Vehicle up pitch while performing the YoYo behavior. 20.0 Vehicle down pitch while performing the YoYo behavior. -15.0 Latitude of waypoint. 36.120 Longitude of waypoint. -123.486 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications, if the vehicle has a positive pitch. If the vehicle has a negative pitch, it will wait up to 10 minutes more to begin ascent. 120 Speed while performing the YoYo behavior. 1 Maximum length of mission 36 Buoyancy bladder position while performing the YoYo behavior. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. 0 Safety behavior that keeps vehicle off sea floor Safety behavior that keeps vehicle from going too deep 0 Safety behavior that keeps vehicle away from the shore 20 0 10 If we're executing here or below, lets' get science data! 2012-09-18T21:42:04.798Z,1348004524.798 [CommandLine](IMPORTANT): Loaded ./Missions/Science/science_to_67_70.xml 2012-09-18T21:42:04.799Z,1348004524.799 [CommandLine](IMPORTANT): got command set science_to_67_70.WptLat 36.308430 degree 2012-09-18T21:42:04.800Z,1348004524.800 [CommandLine](IMPORTANT): got command set science_to_67_70.WptLon -122.055359 degree 2012-09-18T21:42:04.800Z,1348004524.800 [CommandLine](IMPORTANT): got command set science_to_67_70.NeedCommsTime 60.000000 minute 2012-09-18T21:42:04.801Z,1348004524.801 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleAanderaaO2 0.000000 bool 2012-09-18T21:42:04.806Z,1348004524.806 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleISUS 0.000000 bool 2012-09-18T21:42:04.807Z,1348004524.807 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleNeilBrown 1.000000 bool 2012-09-18T21:42:04.808Z,1348004524.808 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleWetLabsBB2FL 1.000000 bool 2012-09-18T21:42:04.808Z,1348004524.809 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.EnabledDVL 1.000000 bool 2012-09-18T21:42:04.809Z,1348004524.809 [CommandLine](IMPORTANT): got command run 2012-09-18T21:42:04.809Z,1348004524.809 [CommandLine](IMPORTANT): Running 2012-09-18T21:42:05.356Z,1348004525.356 [Default] Stopped 2012-09-18T21:42:05.356Z,1348004525.356 [Default](INFO): Aggregate::uninitialize Default 2012-09-18T21:42:05.356Z,1348004525.356 [Default:Iridium] Stopped 2012-09-18T21:42:05.356Z,1348004525.356 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-09-18T21:42:05.357Z,1348004525.357 [Default:Iridium:A.SetSpeed] Stopped 2012-09-18T21:42:05.357Z,1348004525.357 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-09-18T21:42:05.357Z,1348004525.357 [Default:Iridium:Read_Iridium] Stopped 2012-09-18T21:42:05.357Z,1348004525.357 [Default:D.SetSpeed] Stopped 2012-09-18T21:42:05.357Z,1348004525.357 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-09-18T21:42:05.357Z,1348004525.357 [Default:E.GoToSurface] Stopped 2012-09-18T21:42:05.357Z,1348004525.357 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:42:05.357Z,1348004525.357 [MissionManager](IMPORTANT): Started mission science_to_67_70 2012-09-18T21:42:05.357Z,1348004525.357 [science_to_67_70] Running Loop=1 2012-09-18T21:42:05.357Z,1348004525.358 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70 2012-09-18T21:42:05.358Z,1348004525.358 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-09-18T21:42:05.358Z,1348004525.358 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-09-18T21:42:05.359Z,1348004525.359 [science_to_67_70:Science] Running Loop=1 2012-09-18T21:42:05.359Z,1348004525.359 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science 2012-09-18T21:42:05.359Z,1348004525.359 [science_to_67_70:Science:A] Running Loop=1 2012-09-18T21:42:05.359Z,1348004525.359 [science_to_67_70:Science:B] Running Loop=1 2012-09-18T21:42:05.359Z,1348004525.359 [science_to_67_70:Science:C] Running Loop=1 2012-09-18T21:42:05.359Z,1348004525.359 [science_to_67_70:Science:D] Running Loop=1 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:Science:E] Running Loop=1 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:Science:F] Running Loop=1 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:Science:G] Running Loop=1 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:H.SetSpeed](DEBUG): Initialize. 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-09-18T21:42:05.360Z,1348004525.360 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-09-18T21:42:05.361Z,1348004525.361 [science_to_67_70:J.YoYo] Running Loop=1 2012-09-18T21:42:05.361Z,1348004525.361 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent. 2012-09-18T21:42:05.362Z,1348004525.362 [science_to_67_70:SURFACECOMMS] Running Loop=1 2012-09-18T21:42:05.362Z,1348004525.362 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS 2012-09-18T21:42:05.362Z,1348004525.362 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-09-18T21:42:05.362Z,1348004525.362 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:42:05.363Z,1348004525.363 [science_to_67_70:J.YoYo] Running Loop=1 2012-09-18T21:42:05.369Z,1348004525.369 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-09-18T21:42:05.375Z,1348004525.375 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-09-18T21:42:05.380Z,1348004525.380 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-09-18T21:42:05.385Z,1348004525.385 [science_to_67_70:Science] Running Loop=1 2012-09-18T21:42:05.387Z,1348004525.387 [science_to_67_70:Science:PeakDetectChl] Running Loop=1 2012-09-18T21:42:05.387Z,1348004525.387 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl 2012-09-18T21:42:05.387Z,1348004525.387 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2012-09-18T21:42:05.387Z,1348004525.387 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize. 2012-09-18T21:42:05.394Z,1348004525.394 [science_to_67_70:Science:G] Running Loop=1 2012-09-18T21:42:05.395Z,1348004525.395 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2012-09-18T21:42:05.397Z,1348004525.397 [science_to_67_70:Science:F] Running Loop=1 2012-09-18T21:42:05.397Z,1348004525.397 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI 2012-09-18T21:42:05.399Z,1348004525.399 [science_to_67_70:Science:E] Running Loop=1 2012-09-18T21:42:05.400Z,1348004525.400 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity 2012-09-18T21:42:05.401Z,1348004525.401 [science_to_67_70:Science:D] Running Loop=1 2012-09-18T21:42:05.402Z,1348004525.402 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond 2012-09-18T21:42:05.404Z,1348004525.404 [science_to_67_70:Science:C] Running Loop=1 2012-09-18T21:42:05.405Z,1348004525.405 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-09-18T21:42:05.405Z,1348004525.405 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-09-18T21:42:05.412Z,1348004525.412 [science_to_67_70:SURFACECOMMS:B] Running Loop=1 2012-09-18T21:42:05.412Z,1348004525.412 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B 2012-09-18T21:42:05.412Z,1348004525.412 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1 2012-09-18T21:42:05.413Z,1348004525.413 [science_to_67_70:OffshoreWrapper] Running Loop=1 2012-09-18T21:42:05.413Z,1348004525.413 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper 2012-09-18T21:42:05.413Z,1348004525.413 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-09-18T21:42:05.413Z,1348004525.413 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-09-18T21:42:05.413Z,1348004525.413 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1 2012-09-18T21:42:05.413Z,1348004525.413 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-09-18T21:42:05.418Z,1348004525.418 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-09-18T21:42:05.424Z,1348004525.424 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-09-18T21:42:05.428Z,1348004525.428 [science_to_67_70:AltEnvWrapper] Running Loop=1 2012-09-18T21:42:05.429Z,1348004525.429 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper 2012-09-18T21:42:05.429Z,1348004525.429 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-09-18T21:42:05.429Z,1348004525.429 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-09-18T21:42:05.429Z,1348004525.429 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1 2012-09-18T21:42:05.429Z,1348004525.429 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-09-18T21:42:05.434Z,1348004525.434 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-09-18T21:42:05.670Z,1348004525.670 [DVL_micro](INFO): resume:Powering up 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:J.YoYo] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:I.DepthEnvelope] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:H.SetSpeed] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:G.Buoyancy] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:Science] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:Science:C] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:Science:D] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:Science:E] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:Science:F] Preempted 2012-09-18T21:42:05.756Z,1348004525.756 [science_to_67_70:Science:G] Preempted 2012-09-18T21:42:05.757Z,1348004525.757 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-09-18T21:42:05.759Z,1348004525.759 [science_to_67_70:SURFACECOMMS:B:A] Stopped 2012-09-18T21:42:05.759Z,1348004525.759 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1 2012-09-18T21:42:06.152Z,1348004526.152 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-09-18T21:42:06.165Z,1348004526.165 [Reporter](INFO): Turbulence_NPS.component_voltage no_value 2012-09-18T21:42:06.953Z,1348004526.953 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2012-09-18T21:42:07.178Z,1348004527.178 [DVL_micro](INFO): Querying output modes 2012-09-18T21:42:07.178Z,1348004527.178 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-09-18T21:42:07.186Z,1348004527.186 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-09-18T21:42:07.186Z,1348004527.187 [DVL_micro](INFO): NQ1 output enabled 2012-09-18T21:42:07.187Z,1348004527.187 [DVL_micro](INFO): RSSI output enabled 2012-09-18T21:42:07.187Z,1348004527.187 [DVL_micro](INFO): ADCP output enabled 2012-09-18T21:42:07.187Z,1348004527.187 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-09-18T21:42:07.194Z,1348004527.194 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-09-18T21:42:07.196Z,1348004527.196 [DVL_micro](INFO): RSSI unrequested 2012-09-18T21:42:07.196Z,1348004527.196 [DVL_micro](INFO): ADCP unrequested 2012-09-18T21:42:19.223Z,1348004539.223 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-09-18T21:42:19.383Z,1348004539.383 [science_to_67_70:SURFACECOMMS:B:B] Stopped 2012-09-18T21:42:19.384Z,1348004539.384 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1 2012-09-18T21:42:19.786Z,1348004539.786 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-09-18T21:42:19.788Z,1348004539.788 [science_to_67_70:SURFACECOMMS:B:C] Stopped 2012-09-18T21:42:19.788Z,1348004539.788 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B 2012-09-18T21:42:19.788Z,1348004539.788 [science_to_67_70:SURFACECOMMS:B] Stopped 2012-09-18T21:42:19.788Z,1348004539.788 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B 2012-09-18T21:42:19.789Z,1348004539.789 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS 2012-09-18T21:42:19.789Z,1348004539.789 [science_to_67_70:SURFACECOMMS] Stopped 2012-09-18T21:42:19.789Z,1348004539.789 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS 2012-09-18T21:42:19.789Z,1348004539.789 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped 2012-09-18T21:42:19.789Z,1348004539.789 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:42:19.790Z,1348004539.790 [science_to_67_70:Wpt.Point] Running Loop=1 2012-09-18T21:42:19.790Z,1348004539.790 [science_to_67_70:Wpt.Point](DEBUG): Initialize. 2012-09-18T21:42:20.192Z,1348004540.192 [science_to_67_70:J.YoYo] Running Loop=1 2012-09-18T21:42:20.192Z,1348004540.192 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-09-18T21:42:20.193Z,1348004540.193 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-09-18T21:42:20.193Z,1348004540.193 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-09-18T21:42:20.194Z,1348004540.194 [science_to_67_70:Science] Running Loop=1 2012-09-18T21:42:20.199Z,1348004540.199 [science_to_67_70:Science:G] Running Loop=1 2012-09-18T21:42:20.199Z,1348004540.199 [science_to_67_70:Science:F] Running Loop=1 2012-09-18T21:42:20.199Z,1348004540.199 [science_to_67_70:Science:E] Running Loop=1 2012-09-18T21:42:20.199Z,1348004540.199 [science_to_67_70:Science:D] Running Loop=1 2012-09-18T21:42:20.200Z,1348004540.200 [science_to_67_70:Science:C] Running Loop=1 2012-09-18T21:42:20.343Z,1348004540.343 [WetLabsBB2FL](INFO): Powering up 2012-09-18T21:42:22.351Z,1348004542.351 [Reporter](INFO): Turbulence_NPS.component_voltage 11.840940 V 2012-09-18T21:42:23.626Z,1348004543.625 [Reporter](INFO): Turbulence_NPS.component_voltage 11.840940 V 2012-09-18T21:42:26.075Z,1348004546.075 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:27.507Z,1348004547.507 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:28.212Z,1348004548.212 [NAL9602](INFO): SBD MO Status=2, MOMSN=1285, MT Status=2, MTMSN=0 2012-09-18T21:42:28.212Z,1348004548.212 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2012-09-18T21:42:28.856Z,1348004548.856 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:29.811Z,1348004549.811 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:31.184Z,1348004551.184 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:34.081Z,1348004554.081 [NAL9602](INFO): Powering down 2012-09-18T21:42:34.809Z,1348004554.809 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:36.950Z,1348004556.950 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:39.701Z,1348004559.701 [Reporter](INFO): Turbulence_NPS.component_voltage 11.840940 V 2012-09-18T21:42:45.859Z,1348004565.859 [Reporter](INFO): Turbulence_NPS.component_voltage 11.790660 V 2012-09-18T21:42:46.909Z,1348004566.909 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:48.258Z,1348004568.258 [Reporter](INFO): Turbulence_NPS.component_voltage 11.840940 V 2012-09-18T21:42:49.343Z,1348004569.343 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:51.598Z,1348004571.598 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:51.748Z,1348004571.748 [CommandLine](IMPORTANT): got command report touch DVL_micro.component_voltage 2012-09-18T21:42:52.680Z,1348004572.680 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:54.153Z,1348004574.152 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:42:58.785Z,1348004578.785 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:00.085Z,1348004580.085 [Reporter](INFO): Turbulence_NPS.component_voltage 11.790660 V 2012-09-18T21:43:01.519Z,1348004581.519 [Reporter](INFO): Turbulence_NPS.component_voltage 11.840940 V 2012-09-18T21:43:02.472Z,1348004582.472 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:05.471Z,1348004585.471 [CTD_NeilBrown](ERROR): Bad response: -0.13 2012-09-18T21:43:07.515Z,1348004587.515 [Reporter](INFO): Turbulence_NPS.component_voltage 11.790660 V 2012-09-18T21:43:08.567Z,1348004588.567 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:12.161Z,1348004592.161 [Reporter](INFO): Turbulence_NPS.component_voltage 11.790660 V 2012-09-18T21:43:13.536Z,1348004593.536 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:15.843Z,1348004595.843 [Reporter](INFO): Turbulence_NPS.component_voltage 11.790660 V 2012-09-18T21:43:20.939Z,1348004600.939 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:21.828Z,1348004601.828 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:24.377Z,1348004604.377 [Reporter](INFO): Turbulence_NPS.component_voltage 11.840940 V 2012-09-18T21:43:26.681Z,1348004606.681 [Reporter](INFO): Turbulence_NPS.component_voltage 11.840940 V 2012-09-18T21:43:29.422Z,1348004609.422 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:31.723Z,1348004611.723 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:35.207Z,1348004615.207 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:38.770Z,1348004618.770 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:41.475Z,1348004621.475 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:42.507Z,1348004622.507 [Reporter](INFO): Turbulence_NPS.component_voltage 11.815801 V 2012-09-18T21:43:42.707Z,1348004622.707 [CommandLine](IMPORTANT): got command report clear 2012-09-18T21:44:05.891Z,1348004645.891 [CommandLine](IMPORTANT): got command show variable dvl 2012-09-18T21:44:05.895Z,1348004645.895 [CommandLine](INFO): Config/vehicle.DVL_micro_card (none) 2012-09-18T21:44:05.895Z,1348004645.895 [CommandLine](INFO): Config/vehicle.DVL_micro_uart (none) 2012-09-18T21:44:05.895Z,1348004645.895 [CommandLine](INFO): Config/vehicle.DVL_micro_baud (bit_per_second) 2012-09-18T21:44:05.899Z,1348004645.899 [CommandLine](INFO): Config/Sensor.DVL_micro_enable (bool) 2012-09-18T21:44:05.900Z,1348004645.900 [CommandLine](INFO): Config/Sensor.DVL_micro_useHardware (bool) 2012-09-18T21:44:05.900Z,1348004645.900 [CommandLine](INFO): Config/Sensor.DVL_micro_magDeviation (degree) 2012-09-18T21:44:05.900Z,1348004645.900 [CommandLine](INFO): Config/Sensor.DVL_micro_pitchOffset (degree) 2012-09-18T21:44:05.901Z,1348004645.901 [CommandLine](INFO): Config/Sensor.DVL_micro_rollOffset (degree) 2012-09-18T21:44:05.901Z,1348004645.901 [CommandLine](INFO): Config/Sensor.DVL_micro_power (watt) 2012-09-18T21:44:05.921Z,1348004645.921 [CommandLine](INFO): DVL_micro.height_above_sea_floor (meter) 2012-09-18T21:44:05.921Z,1348004645.921 [CommandLine](INFO): DVL_micro.platform_orientation (degree) 2012-09-18T21:44:05.922Z,1348004645.922 [CommandLine](INFO): DVL_micro.platform_magnetic_orientation (degree) 2012-09-18T21:44:05.935Z,1348004645.935 [CommandLine](INFO): DVL_micro.platform_pitch_angle (degree) 2012-09-18T21:44:05.936Z,1348004645.936 [CommandLine](INFO): DVL_micro.platform_roll_angle (degree) 2012-09-18T21:44:05.936Z,1348004645.936 [CommandLine](INFO): DVL_micro.platform_speed_wrt_ground (meter_per_second) 2012-09-18T21:44:05.937Z,1348004645.937 [CommandLine](INFO): DVL_micro.platform_speed_wrt_sea_water (meter_per_second) 2012-09-18T21:44:05.937Z,1348004645.937 [CommandLine](INFO): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second) 2012-09-18T21:44:05.938Z,1348004645.938 [CommandLine](INFO): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second) 2012-09-18T21:44:05.959Z,1348004645.959 [CommandLine](INFO): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second) 2012-09-18T21:44:05.960Z,1348004645.960 [CommandLine](INFO): DVL_micro.platform_x_velocity_wrt_sea_water (meter_per_second) 2012-09-18T21:44:05.960Z,1348004645.960 [CommandLine](INFO): DVL_micro.platform_y_velocity_wrt_sea_water (meter_per_second) 2012-09-18T21:44:05.961Z,1348004645.961 [CommandLine](INFO): DVL_micro.platform_z_velocity_wrt_sea_water (meter_per_second) 2012-09-18T21:44:05.961Z,1348004645.961 [CommandLine](INFO): DVL_micro.Status (count) 2012-09-18T21:44:05.961Z,1348004645.961 [CommandLine](INFO): DVL_micro.Beam1Good (count) 2012-09-18T21:44:05.962Z,1348004645.962 [CommandLine](INFO): DVL_micro.Beam2Good (count) 2012-09-18T21:44:05.971Z,1348004645.971 [CommandLine](INFO): DVL_micro.Beam3Good (count) 2012-09-18T21:44:05.972Z,1348004645.972 [CommandLine](INFO): DVL_micro.Beam4Good (count) 2012-09-18T21:44:05.972Z,1348004645.972 [CommandLine](INFO): DVL_micro.Altitude1 (meter) 2012-09-18T21:44:05.973Z,1348004645.973 [CommandLine](INFO): DVL_micro.Altitude2 (meter) 2012-09-18T21:44:05.973Z,1348004645.973 [CommandLine](INFO): DVL_micro.Altitude3 (meter) 2012-09-18T21:44:05.973Z,1348004645.973 [CommandLine](INFO): DVL_micro.Altitude4 (meter) 2012-09-18T21:44:05.983Z,1348004645.983 [CommandLine](INFO): DVL_micro.BottomVelocityFlag (count) 2012-09-18T21:44:05.983Z,1348004645.983 [CommandLine](INFO): DVL_micro.WaterVelocityFlag (count) 2012-09-18T21:44:05.983Z,1348004645.983 [CommandLine](INFO): DVL_micro.Beam1RSSI (decibel) 2012-09-18T21:44:05.984Z,1348004645.984 [CommandLine](INFO): DVL_micro.Beam2RSSI (decibel) 2012-09-18T21:44:05.984Z,1348004645.984 [CommandLine](INFO): DVL_micro.Beam3RSSI (decibel) 2012-09-18T21:44:05.984Z,1348004645.984 [CommandLine](INFO): DVL_micro.Beam4RSSI (decibel) 2012-09-18T21:44:05.985Z,1348004645.985 [CommandLine](INFO): DVL_micro.RSSIFilterLength (count) 2012-09-18T21:44:05.985Z,1348004645.985 [CommandLine](INFO): DVL_micro.RSSIPoints (count) 2012-09-18T21:44:05.985Z,1348004645.985 [CommandLine](INFO): DVL_micro.AdcpNumBins (count) 2012-09-18T21:44:05.986Z,1348004645.986 [CommandLine](INFO): DVL_micro.AdcpStartDepth (meter) 2012-09-18T21:44:05.995Z,1348004645.995 [CommandLine](INFO): DVL_micro.AdcpXWaterVelocity (meter_per_second) 2012-09-18T21:44:05.995Z,1348004645.995 [CommandLine](INFO): DVL_micro.AdcpYWaterVelocity (meter_per_second) 2012-09-18T21:44:05.995Z,1348004645.995 [CommandLine](INFO): DVL_micro.AdcpZWaterVelocity (meter_per_second) 2012-09-18T21:44:05.997Z,1348004645.997 [CommandLine](INFO): DVL_micro.component_voltage (volt) 2012-09-18T21:44:05.998Z,1348004645.997 [CommandLine](INFO): DVL_micro.component_avgVoltage (volt) 2012-09-18T21:44:05.998Z,1348004645.998 [CommandLine](INFO): DVL_micro.component_current (milliampere) 2012-09-18T21:44:06.020Z,1348004646.020 [CommandLine](INFO): DVL_micro.component_avgCurrent (milliampere) 2012-09-18T21:44:06.031Z,1348004646.031 [CommandLine](INFO): DVL_micro.durationOfLastRun (second) 2012-09-18T21:44:06.043Z,1348004646.043 [CommandLine](INFO): science_to_67_70:Science.EnabledDVL (none) 2012-09-18T21:44:34.678Z,1348004674.678 [CommandLine](IMPORTANT): got command get science_to_67_70:Science.EnabledDVL 2012-09-18T21:44:34.687Z,1348004674.687 [CommandLine](IMPORTANT): science_to_67_70:Science.EnabledDVL 1.000000 none 2012-09-18T21:44:41.347Z,1348004681.347 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.EnabledDVL 1.000000 bool 2012-09-18T21:44:43.983Z,1348004683.983 [CommandLine](IMPORTANT): got command report 2012-09-18T21:44:55.523Z,1348004695.523 [CommandLine](IMPORTANT): got command show variable DVL 2012-09-18T21:44:55.528Z,1348004695.528 [CommandLine](INFO): Config/vehicle.DVL_micro_card (none) 2012-09-18T21:44:55.528Z,1348004695.528 [CommandLine](INFO): Config/vehicle.DVL_micro_uart (none) 2012-09-18T21:44:55.529Z,1348004695.529 [CommandLine](INFO): Config/vehicle.DVL_micro_baud (bit_per_second) 2012-09-18T21:44:55.533Z,1348004695.533 [CommandLine](INFO): Config/Sensor.DVL_micro_enable (bool) 2012-09-18T21:44:55.533Z,1348004695.533 [CommandLine](INFO): Config/Sensor.DVL_micro_useHardware (bool) 2012-09-18T21:44:55.534Z,1348004695.534 [CommandLine](INFO): Config/Sensor.DVL_micro_magDeviation (degree) 2012-09-18T21:44:55.534Z,1348004695.534 [CommandLine](INFO): Config/Sensor.DVL_micro_pitchOffset (degree) 2012-09-18T21:44:55.535Z,1348004695.535 [CommandLine](INFO): Config/Sensor.DVL_micro_rollOffset (degree) 2012-09-18T21:44:55.535Z,1348004695.535 [CommandLine](INFO): Config/Sensor.DVL_micro_power (watt) 2012-09-18T21:44:55.581Z,1348004695.581 [CommandLine](INFO): DVL_micro.height_above_sea_floor (meter) 2012-09-18T21:44:55.581Z,1348004695.581 [CommandLine](INFO): DVL_micro.platform_orientation (degree) 2012-09-18T21:44:55.581Z,1348004695.582 [CommandLine](INFO): DVL_micro.platform_magnetic_orientation (degree) 2012-09-18T21:44:55.582Z,1348004695.582 [CommandLine](INFO): DVL_micro.platform_pitch_angle (degree) 2012-09-18T21:44:55.591Z,1348004695.591 [CommandLine](INFO): DVL_micro.platform_roll_angle (degree) 2012-09-18T21:44:55.592Z,1348004695.592 [CommandLine](INFO): DVL_micro.platform_speed_wrt_ground (meter_per_second) 2012-09-18T21:44:55.593Z,1348004695.593 [CommandLine](INFO): DVL_micro.platform_speed_wrt_sea_water (meter_per_second) 2012-09-18T21:44:55.593Z,1348004695.593 [CommandLine](INFO): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second) 2012-09-18T21:44:55.594Z,1348004695.594 [CommandLine](INFO): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second) 2012-09-18T21:44:55.603Z,1348004695.603 [CommandLine](INFO): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second) 2012-09-18T21:44:55.604Z,1348004695.604 [CommandLine](INFO): DVL_micro.platform_x_velocity_wrt_sea_water (meter_per_second) 2012-09-18T21:44:55.605Z,1348004695.605 [CommandLine](INFO): DVL_micro.platform_y_velocity_wrt_sea_water (meter_per_second) 2012-09-18T21:44:55.605Z,1348004695.605 [CommandLine](INFO): DVL_micro.platform_z_velocity_wrt_sea_water (meter_per_second) 2012-09-18T21:44:55.605Z,1348004695.605 [CommandLine](INFO): DVL_micro.Status (count) 2012-09-18T21:44:55.606Z,1348004695.606 [CommandLine](INFO): DVL_micro.Beam1Good (count) 2012-09-18T21:44:55.627Z,1348004695.627 [CommandLine](INFO): DVL_micro.Beam2Good (count) 2012-09-18T21:44:55.627Z,1348004695.627 [CommandLine](INFO): DVL_micro.Beam3Good (count) 2012-09-18T21:44:55.627Z,1348004695.628 [CommandLine](INFO): DVL_micro.Beam4Good (count) 2012-09-18T21:44:55.628Z,1348004695.628 [CommandLine](INFO): DVL_micro.Altitude1 (meter) 2012-09-18T21:44:55.628Z,1348004695.628 [CommandLine](INFO): DVL_micro.Altitude2 (meter) 2012-09-18T21:44:55.628Z,1348004695.628 [CommandLine](INFO): DVL_micro.Altitude3 (meter) 2012-09-18T21:44:55.629Z,1348004695.629 [CommandLine](INFO): DVL_micro.Altitude4 (meter) 2012-09-18T21:44:55.629Z,1348004695.629 [CommandLine](INFO): DVL_micro.BottomVelocityFlag (count) 2012-09-18T21:44:55.630Z,1348004695.630 [CommandLine](INFO): DVL_micro.WaterVelocityFlag (count) 2012-09-18T21:44:55.630Z,1348004695.630 [CommandLine](INFO): DVL_micro.Beam1RSSI (decibel) 2012-09-18T21:44:55.639Z,1348004695.639 [CommandLine](INFO): DVL_micro.Beam2RSSI (decibel) 2012-09-18T21:44:55.639Z,1348004695.639 [CommandLine](INFO): DVL_micro.Beam3RSSI (decibel) 2012-09-18T21:44:55.640Z,1348004695.640 [CommandLine](INFO): DVL_micro.Beam4RSSI (decibel) 2012-09-18T21:44:55.640Z,1348004695.640 [CommandLine](INFO): DVL_micro.RSSIFilterLength (count) 2012-09-18T21:44:55.640Z,1348004695.640 [CommandLine](INFO): DVL_micro.RSSIPoints (count) 2012-09-18T21:44:55.641Z,1348004695.641 [CommandLine](INFO): DVL_micro.AdcpNumBins (count) 2012-09-18T21:44:55.641Z,1348004695.641 [CommandLine](INFO): DVL_micro.AdcpStartDepth (meter) 2012-09-18T21:44:55.641Z,1348004695.641 [CommandLine](INFO): DVL_micro.AdcpXWaterVelocity (meter_per_second) 2012-09-18T21:44:55.642Z,1348004695.642 [CommandLine](INFO): DVL_micro.AdcpYWaterVelocity (meter_per_second) 2012-09-18T21:44:55.650Z,1348004695.651 [CommandLine](INFO): DVL_micro.AdcpZWaterVelocity (meter_per_second) 2012-09-18T21:44:55.652Z,1348004695.652 [CommandLine](INFO): DVL_micro.component_voltage (volt) 2012-09-18T21:44:55.653Z,1348004695.653 [CommandLine](INFO): DVL_micro.component_avgVoltage (volt) 2012-09-18T21:44:55.653Z,1348004695.653 [CommandLine](INFO): DVL_micro.component_current (milliampere) 2012-09-18T21:44:55.654Z,1348004695.654 [CommandLine](INFO): DVL_micro.component_avgCurrent (milliampere) 2012-09-18T21:44:55.664Z,1348004695.664 [CommandLine](INFO): DVL_micro.durationOfLastRun (second) 2012-09-18T21:44:55.687Z,1348004695.687 [CommandLine](INFO): science_to_67_70:Science.EnabledDVL (none) 2012-09-18T21:45:05.352Z,1348004705.352 [CTD_NeilBrown](ERROR): Bad response: 44.87586 2012-09-18T21:45:07.479Z,1348004707.479 [CommandLine](IMPORTANT): got command report touch DVL_micro.component_avgCurrent 2012-09-18T21:45:11.407Z,1348004711.407 [CommandLine](IMPORTANT): got command show stack 2012-09-18T21:45:11.407Z,1348004711.407 [CommandLine](INFO): Behavior Stack: 2012-09-18T21:45:11.408Z,1348004711.408 [science_to_67_70:AltEnvWrapper](INFO): Priority 0: science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope 2012-09-18T21:45:11.408Z,1348004711.408 [science_to_67_70:AltEnvWrapper](INFO): Priority 1: science_to_67_70:AltEnvWrapper:B.Wait 2012-09-18T21:45:11.408Z,1348004711.408 [science_to_67_70](INFO): Priority 2: science_to_67_70:B.DepthEnvelope 2012-09-18T21:45:11.409Z,1348004711.409 [science_to_67_70:OffshoreWrapper](INFO): Priority 3: science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope 2012-09-18T21:45:11.409Z,1348004711.409 [science_to_67_70:OffshoreWrapper](INFO): Priority 4: science_to_67_70:OffshoreWrapper:B.Wait 2012-09-18T21:45:11.410Z,1348004711.410 [science_to_67_70:Science](INFO): Priority 5: science_to_67_70:Science:C 2012-09-18T21:45:11.410Z,1348004711.410 [science_to_67_70:Science](INFO): Priority 6: science_to_67_70:Science:D 2012-09-18T21:45:11.411Z,1348004711.411 [science_to_67_70:Science](INFO): Priority 7: science_to_67_70:Science:E 2012-09-18T21:45:11.411Z,1348004711.411 [science_to_67_70:Science](INFO): Priority 8: science_to_67_70:Science:F 2012-09-18T21:45:11.411Z,1348004711.411 [science_to_67_70:Science](INFO): Priority 9: science_to_67_70:Science:G 2012-09-18T21:45:11.412Z,1348004711.411 [science_to_67_70:Science:PeakDetectChl](INFO): Priority 10: science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth 2012-09-18T21:45:11.412Z,1348004711.412 [science_to_67_70](INFO): Priority 11: science_to_67_70:G.Buoyancy 2012-09-18T21:45:11.412Z,1348004711.412 [science_to_67_70](INFO): Priority 12: science_to_67_70:H.SetSpeed 2012-09-18T21:45:11.413Z,1348004711.413 [science_to_67_70](INFO): Priority 13: science_to_67_70:I.DepthEnvelope 2012-09-18T21:45:11.413Z,1348004711.413 [science_to_67_70](INFO): Priority 14: science_to_67_70:J.YoYo 2012-09-18T21:45:11.413Z,1348004711.413 [science_to_67_70](INFO): Priority 15: science_to_67_70:Wpt.Point 2012-09-18T21:45:18.051Z,1348004718.051 [CommandLine](IMPORTANT): got command stop 2012-09-18T21:45:18.224Z,1348004718.224 [science_to_67_70] Stopped 2012-09-18T21:45:18.224Z,1348004718.224 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70 2012-09-18T21:45:18.224Z,1348004718.224 [science_to_67_70:AltEnvWrapper] Stopped 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:B.DepthEnvelope] Stopped 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize. 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:OffshoreWrapper] Stopped 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped 2012-09-18T21:45:18.225Z,1348004718.225 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-09-18T21:45:18.226Z,1348004718.226 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped 2012-09-18T21:45:18.226Z,1348004718.226 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-09-18T21:45:18.226Z,1348004718.226 [science_to_67_70:Science] Stopped 2012-09-18T21:45:18.226Z,1348004718.226 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science 2012-09-18T21:45:18.226Z,1348004718.226 [science_to_67_70:Science:A] Stopped 2012-09-18T21:45:18.226Z,1348004718.226 [science_to_67_70:Science:B] Stopped 2012-09-18T21:45:18.230Z,1348004718.230 [science_to_67_70:Science:C] Stopped 2012-09-18T21:45:18.230Z,1348004718.230 [science_to_67_70:Science:D] Stopped 2012-09-18T21:45:18.230Z,1348004718.230 [science_to_67_70:Science:E] Stopped 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:Science:F] Stopped 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:Science:G] Stopped 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:Science:PeakDetectChl] Stopped 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:G.Buoyancy] Stopped 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:H.SetSpeed] Stopped 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize. 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:I.DepthEnvelope] Stopped 2012-09-18T21:45:18.231Z,1348004718.231 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize. 2012-09-18T21:45:18.232Z,1348004718.232 [science_to_67_70:J.YoYo] Stopped 2012-09-18T21:45:18.232Z,1348004718.232 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent. 2012-09-18T21:45:18.232Z,1348004718.232 [science_to_67_70:Wpt.Point] Stopped 2012-09-18T21:45:18.394Z,1348004718.394 [WetLabsBB2FL](INFO): Powering down 2012-09-18T21:45:18.664Z,1348004718.664 [MissionManager](IMPORTANT): Started mission Default 2012-09-18T21:45:18.664Z,1348004718.664 [Default] Running Loop=1 2012-09-18T21:45:18.664Z,1348004718.664 [Default](INFO): Aggregate::initialize Default 2012-09-18T21:45:18.664Z,1348004718.664 [Default:D.SetSpeed] Running Loop=1 2012-09-18T21:45:18.664Z,1348004718.664 [Default:D.SetSpeed](DEBUG): Initialize. 2012-09-18T21:45:18.664Z,1348004718.664 [Default:E.GoToSurface] Running Loop=1 2012-09-18T21:45:18.665Z,1348004718.665 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:45:18.665Z,1348004718.665 [Default:Iridium] Running Loop=1 2012-09-18T21:45:18.665Z,1348004718.665 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-09-18T21:45:18.665Z,1348004718.665 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-09-18T21:45:18.665Z,1348004718.665 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-09-18T21:45:18.665Z,1348004718.665 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-09-18T21:45:18.665Z,1348004718.665 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:45:18.672Z,1348004718.672 [Default:Iridium:B.GoToSurface] Stopped 2012-09-18T21:45:18.672Z,1348004718.672 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:45:18.672Z,1348004718.672 [Default:Iridium:Read_Iridium] Running Loop=1 2012-09-18T21:45:19.394Z,1348004719.394 [NAL9602](INFO): Powering up 2012-09-18T21:45:29.843Z,1348004729.843 [NAL9602](INFO): NAL9602 initialized 2012-09-18T21:45:47.875Z,1348004747.875 [CommandLine](IMPORTANT): got command load ./Missions/Science/science_to_67_70.xml 2012-09-18T21:45:47.875Z,1348004747.875 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml 2012-09-18T21:45:47.986Z,1348004747.986 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m 2012-09-18T21:45:47.989Z,1348004747.989 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m 2012-09-18T21:45:47.995Z,1348004747.995 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m 2012-09-18T21:45:47.998Z,1348004747.997 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m 2012-09-18T21:45:48.004Z,1348004748.004 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m 2012-09-18T21:45:48.010Z,1348004748.010 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg 2012-09-18T21:45:48.013Z,1348004748.013 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg 2012-09-18T21:45:48.019Z,1348004748.019 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg 2012-09-18T21:45:48.022Z,1348004748.022 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg 2012-09-18T21:45:48.028Z,1348004748.028 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min 2012-09-18T21:45:48.034Z,1348004748.034 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s 2012-09-18T21:45:48.037Z,1348004748.037 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h 2012-09-18T21:45:48.043Z,1348004748.043 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a 2012-09-18T21:45:48.046Z,1348004748.046 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-09-18T21:45:48.095Z,1348004748.095 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait. 2012-09-18T21:45:48.096Z,1348004748.096 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-09-18T21:45:48.120Z,1348004748.120 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-09-18T21:45:48.132Z,1348004748.132 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait. 2012-09-18T21:45:48.133Z,1348004748.133 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-09-18T21:45:48.185Z,1348004748.185 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-09-18T21:45:48.192Z,1348004748.192 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-09-18T21:45:48.199Z,1348004748.199 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s 2012-09-18T21:45:48.201Z,1348004748.201 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min 2012-09-18T21:45:48.207Z,1348004748.207 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-09-18T21:45:48.237Z,1348004748.237 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg 2012-09-18T21:45:48.263Z,1348004748.263 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2012-09-18T21:45:48.454Z,1348004748.454 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool 2012-09-18T21:45:48.459Z,1348004748.459 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool 2012-09-18T21:45:48.461Z,1348004748.461 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool 2012-09-18T21:45:48.467Z,1348004748.467 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool 2012-09-18T21:45:48.469Z,1348004748.469 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool 2012-09-18T21:45:48.480Z,1348004748.480 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleAanderaaO2 = 0.000000 n/a 2012-09-18T21:45:48.491Z,1348004748.491 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleNeilBrown = 1.000000 n/a 2012-09-18T21:45:48.493Z,1348004748.493 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleTurbulence_NPS = 1.000000 n/a 2012-09-18T21:45:48.504Z,1348004748.504 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleWetLabsBB2FL = 1.000000 n/a 2012-09-18T21:45:48.515Z,1348004748.514 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a 2012-09-18T21:45:48.517Z,1348004748.517 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l 2012-09-18T21:45:48.528Z,1348004748.528 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m 2012-09-18T21:45:48.538Z,1348004748.538 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg 2012-09-18T21:45:48.541Z,1348004748.541 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg 2012-09-18T21:45:48.551Z,1348004748.551 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l 2012-09-18T21:45:48.554Z,1348004748.554 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m 2012-09-18T21:45:48.565Z,1348004748.565 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg 2012-09-18T21:45:48.575Z,1348004748.575 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg 2012-09-18T21:45:48.602Z,1348004748.602 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-09-18T21:45:48.634Z,1348004748.634 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-09-18T21:45:48.650Z,1348004748.649 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool 2012-09-18T21:45:48.659Z,1348004748.659 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool 2012-09-18T21:45:48.660Z,1348004748.660 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy. 2012-09-18T21:45:48.671Z,1348004748.671 [science_to_67_70:H.SetSpeed](DEBUG): Construct. 2012-09-18T21:45:48.673Z,1348004748.673 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-09-18T21:45:48.697Z,1348004748.697 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo. 2012-09-18T21:45:48.723Z,1348004748.723 [science_to_67_70:Wpt.Point](DEBUG): Construct. 2012-09-18T21:45:48.783Z,1348004748.783 [MissionManager](DEBUG): Vehicle yo-yo's to the specified waypoint, with science turned on. Minimum height above the sea floor for the entire mission. 10.0 Maximum depth for the entire mission. 105.0 Minimum offshore distance for the entire mission. 2000.0 Minimum depth while performing the YoYo behavior. 3.0 Maximum depth while performing the YoYo behavior. 100.0 Vehicle up pitch while performing the YoYo behavior. 20.0 Vehicle down pitch while performing the YoYo behavior. -15.0 Latitude of waypoint. 36.120 Longitude of waypoint. -123.486 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications, if the vehicle has a positive pitch. If the vehicle has a negative pitch, it will wait up to 10 minutes more to begin ascent. 120 Speed while performing the YoYo behavior. 1 Maximum length of mission 36 Buoyancy bladder position while performing the YoYo behavior. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. 0 Safety behavior that keeps vehicle off sea floor Safety behavior that keeps vehicle from going too deep 0 Safety behavior that keeps vehicle away from the shore 20 0 10 If we're executing here or below, lets' get science data! 2012-09-18T21:45:48.784Z,1348004748.784 [CommandLine](IMPORTANT): Loaded ./Missions/Science/science_to_67_70.xml 2012-09-18T21:45:48.784Z,1348004748.784 [CommandLine](IMPORTANT): got command set science_to_67_70.WptLat 36.308430 degree 2012-09-18T21:45:48.785Z,1348004748.785 [CommandLine](IMPORTANT): got command set science_to_67_70.WptLon -122.055359 degree 2012-09-18T21:45:48.791Z,1348004748.791 [CommandLine](IMPORTANT): got command set science_to_67_70.NeedCommsTime 60.000000 minute 2012-09-18T21:45:48.792Z,1348004748.792 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleAanderaaO2 0.000000 bool 2012-09-18T21:45:48.793Z,1348004748.793 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleISUS 0.000000 bool 2012-09-18T21:45:48.800Z,1348004748.800 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleNeilBrown 1.000000 bool 2012-09-18T21:45:48.808Z,1348004748.808 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleWetLabsBB2FL 1.000000 bool 2012-09-18T21:45:48.808Z,1348004748.809 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.EnabledDVL 1.000000 bool 2012-09-18T21:45:48.809Z,1348004748.809 [CommandLine](IMPORTANT): got command run 2012-09-18T21:45:48.809Z,1348004748.809 [CommandLine](IMPORTANT): Running 2012-09-18T21:45:49.074Z,1348004749.074 [Default] Stopped 2012-09-18T21:45:49.075Z,1348004749.075 [Default](INFO): Aggregate::uninitialize Default 2012-09-18T21:45:49.075Z,1348004749.075 [Default:Iridium] Stopped 2012-09-18T21:45:49.075Z,1348004749.075 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-09-18T21:45:49.075Z,1348004749.075 [Default:Iridium:A.SetSpeed] Stopped 2012-09-18T21:45:49.075Z,1348004749.075 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-09-18T21:45:49.075Z,1348004749.075 [Default:Iridium:Read_Iridium] Stopped 2012-09-18T21:45:49.075Z,1348004749.075 [Default:D.SetSpeed] Stopped 2012-09-18T21:45:49.075Z,1348004749.075 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-09-18T21:45:49.075Z,1348004749.075 [Default:E.GoToSurface] Stopped 2012-09-18T21:45:49.075Z,1348004749.075 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:45:49.075Z,1348004749.076 [MissionManager](IMPORTANT): Started mission science_to_67_70 2012-09-18T21:45:49.076Z,1348004749.076 [science_to_67_70] Running Loop=1 2012-09-18T21:45:49.076Z,1348004749.076 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70 2012-09-18T21:45:49.076Z,1348004749.076 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-09-18T21:45:49.076Z,1348004749.076 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science:A] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science:B] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science:C] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science:D] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science:E] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science:F] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:Science:G] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-09-18T21:45:49.077Z,1348004749.077 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-09-18T21:45:49.078Z,1348004749.078 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-09-18T21:45:49.078Z,1348004749.078 [science_to_67_70:H.SetSpeed](DEBUG): Initialize. 2012-09-18T21:45:49.078Z,1348004749.078 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-09-18T21:45:49.078Z,1348004749.078 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-09-18T21:45:49.083Z,1348004749.083 [science_to_67_70:J.YoYo] Running Loop=1 2012-09-18T21:45:49.083Z,1348004749.083 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent. 2012-09-18T21:45:49.083Z,1348004749.083 [science_to_67_70:SURFACECOMMS] Running Loop=1 2012-09-18T21:45:49.083Z,1348004749.083 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS 2012-09-18T21:45:49.083Z,1348004749.083 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-09-18T21:45:49.083Z,1348004749.083 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:45:49.084Z,1348004749.085 [science_to_67_70:J.YoYo] Running Loop=1 2012-09-18T21:45:49.086Z,1348004749.086 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-09-18T21:45:49.091Z,1348004749.091 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-09-18T21:45:49.092Z,1348004749.092 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-09-18T21:45:49.093Z,1348004749.093 [science_to_67_70:Science] Running Loop=1 2012-09-18T21:45:49.099Z,1348004749.099 [science_to_67_70:Science:PeakDetectChl] Running Loop=1 2012-09-18T21:45:49.099Z,1348004749.099 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl 2012-09-18T21:45:49.099Z,1348004749.099 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2012-09-18T21:45:49.099Z,1348004749.099 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize. 2012-09-18T21:45:49.101Z,1348004749.101 [science_to_67_70:Science:G] Running Loop=1 2012-09-18T21:45:49.102Z,1348004749.102 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2012-09-18T21:45:49.107Z,1348004749.107 [science_to_67_70:Science:F] Running Loop=1 2012-09-18T21:45:49.107Z,1348004749.107 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI 2012-09-18T21:45:49.108Z,1348004749.108 [science_to_67_70:Science:E] Running Loop=1 2012-09-18T21:45:49.109Z,1348004749.109 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity 2012-09-18T21:45:49.110Z,1348004749.110 [science_to_67_70:Science:D] Running Loop=1 2012-09-18T21:45:49.114Z,1348004749.114 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond 2012-09-18T21:45:49.115Z,1348004749.115 [science_to_67_70:Science:C] Running Loop=1 2012-09-18T21:45:49.116Z,1348004749.116 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-09-18T21:45:49.116Z,1348004749.116 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-09-18T21:45:49.123Z,1348004749.123 [science_to_67_70:SURFACECOMMS:B] Running Loop=1 2012-09-18T21:45:49.123Z,1348004749.123 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B 2012-09-18T21:45:49.123Z,1348004749.123 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1 2012-09-18T21:45:49.123Z,1348004749.123 [science_to_67_70:OffshoreWrapper] Running Loop=1 2012-09-18T21:45:49.123Z,1348004749.123 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper 2012-09-18T21:45:49.123Z,1348004749.123 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-09-18T21:45:49.123Z,1348004749.123 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-09-18T21:45:49.123Z,1348004749.124 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1 2012-09-18T21:45:49.124Z,1348004749.124 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-09-18T21:45:49.124Z,1348004749.125 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-09-18T21:45:49.126Z,1348004749.126 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-09-18T21:45:49.131Z,1348004749.131 [science_to_67_70:AltEnvWrapper] Running Loop=1 2012-09-18T21:45:49.131Z,1348004749.131 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper 2012-09-18T21:45:49.131Z,1348004749.131 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-09-18T21:45:49.131Z,1348004749.131 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-09-18T21:45:49.132Z,1348004749.132 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1 2012-09-18T21:45:49.132Z,1348004749.132 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-09-18T21:45:49.133Z,1348004749.133 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-09-18T21:45:49.511Z,1348004749.511 [WetLabsBB2FL](INFO): Powering up 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:J.YoYo] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:I.DepthEnvelope] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:H.SetSpeed] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:G.Buoyancy] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:Science] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:Science:C] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:Science:D] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:Science:E] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:Science:F] Preempted 2012-09-18T21:45:49.553Z,1348004749.553 [science_to_67_70:Science:G] Preempted 2012-09-18T21:45:49.554Z,1348004749.554 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-09-18T21:45:49.559Z,1348004749.559 [science_to_67_70:SURFACECOMMS:B:A] Stopped 2012-09-18T21:45:49.559Z,1348004749.559 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1 2012-09-18T21:45:49.924Z,1348004749.924 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-09-18T21:45:50.702Z,1348004750.702 [WetLabsBB2FL](INFO): Powering down 2012-09-18T21:45:50.743Z,1348004750.743 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2012-09-18T21:45:55.904Z,1348004755.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=1285, MT Status=2, MTMSN=0 2012-09-18T21:45:55.904Z,1348004755.904 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2012-09-18T21:46:11.571Z,1348004771.571 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.900002 degree 2012-09-18T21:46:11.572Z,1348004771.572 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.930000 degree 2012-09-18T21:46:11.572Z,1348004771.572 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-09-18T21:46:11.804Z,1348004771.804 [science_to_67_70:SURFACECOMMS:B:B] Stopped 2012-09-18T21:46:11.804Z,1348004771.804 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1 2012-09-18T21:46:12.215Z,1348004772.215 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-09-18T21:46:12.216Z,1348004772.216 [science_to_67_70:SURFACECOMMS:B:C] Stopped 2012-09-18T21:46:12.216Z,1348004772.216 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B 2012-09-18T21:46:12.216Z,1348004772.216 [science_to_67_70:SURFACECOMMS:B] Stopped 2012-09-18T21:46:12.216Z,1348004772.217 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B 2012-09-18T21:46:12.217Z,1348004772.217 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS 2012-09-18T21:46:12.218Z,1348004772.217 [science_to_67_70:SURFACECOMMS] Stopped 2012-09-18T21:46:12.218Z,1348004772.218 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS 2012-09-18T21:46:12.218Z,1348004772.218 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped 2012-09-18T21:46:12.218Z,1348004772.218 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:46:12.222Z,1348004772.222 [science_to_67_70:Wpt.Point] Running Loop=1 2012-09-18T21:46:12.222Z,1348004772.222 [science_to_67_70:Wpt.Point](DEBUG): Initialize. 2012-09-18T21:46:12.616Z,1348004772.616 [science_to_67_70:J.YoYo] Running Loop=1 2012-09-18T21:46:12.616Z,1348004772.616 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-09-18T21:46:12.617Z,1348004772.617 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-09-18T21:46:12.618Z,1348004772.618 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-09-18T21:46:12.622Z,1348004772.622 [science_to_67_70:Science] Running Loop=1 2012-09-18T21:46:12.623Z,1348004772.623 [science_to_67_70:Science:G] Running Loop=1 2012-09-18T21:46:12.623Z,1348004772.623 [science_to_67_70:Science:F] Running Loop=1 2012-09-18T21:46:12.623Z,1348004772.624 [science_to_67_70:Science:E] Running Loop=1 2012-09-18T21:46:12.624Z,1348004772.624 [science_to_67_70:Science:D] Running Loop=1 2012-09-18T21:46:12.624Z,1348004772.624 [science_to_67_70:Science:C] Running Loop=1 2012-09-18T21:46:12.959Z,1348004772.959 [WetLabsBB2FL](INFO): Powering up 2012-09-18T21:46:15.719Z,1348004775.719 [CTD_NeilBrown](ERROR): Pressure reading out of range: -470.049988 decibar 2012-09-18T21:46:17.849Z,1348004777.849 [NAL9602](INFO): Powering down 2012-09-18T21:46:17.859Z,1348004777.859 [CommandLine](IMPORTANT): got command report 2012-09-18T21:46:17.859Z,1348004777.859 [Reporter](INFO): DVL_micro.component_avgCurrent reporting when touched 2012-09-18T21:46:53.799Z,1348004813.799 [Turbulence_NPS](ERROR): Uart error: serial buffer full 2012-09-18T21:46:53.799Z,1348004813.799 [Turbulence_NPS] Communications Fault, FailCount= 1 2012-09-18T21:46:53.799Z,1348004813.799 [Turbulence_NPS](ERROR): Communications Fault 2012-09-18T21:46:53.801Z,1348004813.801 [Turbulence_NPS](INFO): Powering down 2012-09-18T21:46:53.911Z,1348004813.911 [CBIT](ERROR): Communications Fault in component: Turbulence_NPS 2012-09-18T21:46:53.912Z,1348004813.912 [CBIT](INFO): Clearing failed state for component Turbulence_NPS 2012-09-18T21:46:53.912Z,1348004813.912 [Turbulence_NPS] No Fault, FailCount= 1 2012-09-18T21:46:54.203Z,1348004814.203 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-09-18T21:47:34.744Z,1348004854.744 [CTD_NeilBrown](ERROR): Bad response: 26 2012-09-18T21:47:44.264Z,1348004864.264 [Turbulence_NPS](ERROR): Uart error: serial buffer full 2012-09-18T21:47:44.264Z,1348004864.264 [Turbulence_NPS] Communications Fault, FailCount= 2 2012-09-18T21:47:44.264Z,1348004864.264 [Turbulence_NPS](ERROR): Communications Fault 2012-09-18T21:47:44.266Z,1348004864.266 [Turbulence_NPS](INFO): Powering down 2012-09-18T21:47:44.324Z,1348004864.324 [CBIT](ERROR): Communications Fault in component: Turbulence_NPS 2012-09-18T21:47:44.324Z,1348004864.324 [CBIT](INFO): Clearing failed state for component Turbulence_NPS 2012-09-18T21:47:44.324Z,1348004864.325 [Turbulence_NPS] No Fault, FailCount= 2 2012-09-18T21:47:44.671Z,1348004864.671 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-09-18T21:47:52.507Z,1348004872.507 [CommandLine](IMPORTANT): got command get latitude 2012-09-18T21:47:52.507Z,1348004872.507 [CommandLine](IMPORTANT): latitude 36.900002 degree 2012-09-18T21:47:59.487Z,1348004879.487 [CommandLine](IMPORTANT): got command get longitude 2012-09-18T21:47:59.488Z,1348004879.488 [CommandLine](IMPORTANT): longitude -121.930000 degree 2012-09-18T21:49:22.088Z,1348004962.088 [CTD_NeilBrown](ERROR): Bad response: -0.10 2012-09-18T21:49:23.487Z,1348004963.487 [CommandLine](IMPORTANT): got command report 2012-09-18T21:49:23.487Z,1348004963.487 [Reporter](INFO): DVL_micro.component_avgCurrent reporting when touched 2012-09-18T21:49:27.791Z,1348004967.791 [CommandLine](IMPORTANT): got command stop 2012-09-18T21:49:27.845Z,1348004967.845 [science_to_67_70] Stopped 2012-09-18T21:49:27.846Z,1348004967.846 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70 2012-09-18T21:49:27.846Z,1348004967.846 [science_to_67_70:AltEnvWrapper] Stopped 2012-09-18T21:49:27.853Z,1348004967.853 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper 2012-09-18T21:49:27.854Z,1348004967.854 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped 2012-09-18T21:49:27.854Z,1348004967.854 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-09-18T21:49:27.854Z,1348004967.854 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped 2012-09-18T21:49:27.854Z,1348004967.854 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-09-18T21:49:27.854Z,1348004967.854 [science_to_67_70:B.DepthEnvelope] Stopped 2012-09-18T21:49:27.854Z,1348004967.854 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize. 2012-09-18T21:49:27.871Z,1348004967.871 [science_to_67_70:OffshoreWrapper] Stopped 2012-09-18T21:49:27.871Z,1348004967.871 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper 2012-09-18T21:49:27.871Z,1348004967.871 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped 2012-09-18T21:49:27.871Z,1348004967.871 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science:A] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science:B] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science:C] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science:D] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science:E] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science:F] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science:G] Stopped 2012-09-18T21:49:27.872Z,1348004967.872 [science_to_67_70:Science:PeakDetectChl] Stopped 2012-09-18T21:49:27.873Z,1348004967.872 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl 2012-09-18T21:49:27.873Z,1348004967.873 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2012-09-18T21:49:27.873Z,1348004967.873 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2012-09-18T21:49:27.873Z,1348004967.873 [science_to_67_70:G.Buoyancy] Stopped 2012-09-18T21:49:27.873Z,1348004967.873 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-09-18T21:49:27.873Z,1348004967.874 [science_to_67_70:H.SetSpeed] Stopped 2012-09-18T21:49:27.873Z,1348004967.874 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize. 2012-09-18T21:49:27.874Z,1348004967.874 [science_to_67_70:I.DepthEnvelope] Stopped 2012-09-18T21:49:27.874Z,1348004967.874 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize. 2012-09-18T21:49:27.878Z,1348004967.878 [science_to_67_70:J.YoYo] Stopped 2012-09-18T21:49:27.879Z,1348004967.879 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent. 2012-09-18T21:49:27.879Z,1348004967.879 [science_to_67_70:Wpt.Point] Stopped 2012-09-18T21:49:27.970Z,1348004967.970 [WetLabsBB2FL](INFO): Powering down 2012-09-18T21:49:28.306Z,1348004968.306 [MissionManager](IMPORTANT): Started mission Default 2012-09-18T21:49:28.306Z,1348004968.306 [Default] Running Loop=1 2012-09-18T21:49:28.307Z,1348004968.307 [Default](INFO): Aggregate::initialize Default 2012-09-18T21:49:28.307Z,1348004968.307 [Default:D.SetSpeed] Running Loop=1 2012-09-18T21:49:28.307Z,1348004968.307 [Default:D.SetSpeed](DEBUG): Initialize. 2012-09-18T21:49:28.307Z,1348004968.307 [Default:E.GoToSurface] Running Loop=1 2012-09-18T21:49:28.307Z,1348004968.307 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:49:28.307Z,1348004968.307 [Default:Iridium] Running Loop=1 2012-09-18T21:49:28.307Z,1348004968.307 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-09-18T21:49:28.307Z,1348004968.307 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-09-18T21:49:28.308Z,1348004968.307 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-09-18T21:49:28.308Z,1348004968.308 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-09-18T21:49:28.308Z,1348004968.308 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-09-18T21:49:28.310Z,1348004968.310 [Default:Iridium:B.GoToSurface] Stopped 2012-09-18T21:49:28.310Z,1348004968.310 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-09-18T21:49:28.314Z,1348004968.314 [Default:Iridium:Read_Iridium] Running Loop=1 2012-09-18T21:49:29.046Z,1348004969.046 [NAL9602](INFO): Powering up 2012-09-18T21:49:31.679Z,1348004971.679 [CommandLine](IMPORTANT): got command restart application 2012-09-18T21:49:32.750Z,1348004972.750 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2012-09-18T21:49:32.750Z,1348004972.750 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2012-09-18T21:49:32.819Z,1348004972.819 [WetLabsBB2FL](INFO): Powering down 2012-09-18T21:49:32.826Z,1348004972.826 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler 2012-09-18T21:49:33.187Z,1348004973.187 [Turbulence_NPS](INFO): Powering down 2012-09-18T21:49:33.194Z,1348004973.194 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2012-09-18T21:49:33.443Z,1348004973.443 [CTD_NeilBrown](INFO): Powering down 2012-09-18T21:49:33.450Z,1348004973.450 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler 2012-09-18T21:49:39.875Z,1348004979.875 [NAL9602](INFO): NAL9602 initialized 2012-09-18T21:50:00.680Z,1348005000.680 [NAL9602](INFO): SBD MO Status=2, MOMSN=1285, MT Status=2, MTMSN=0 2012-09-18T21:50:00.680Z,1348005000.680 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2