2015-04-21T21:19:28.427Z,1429651168.427 [Supervisor](DEBUG): Initializing supervisor. 2015-04-21T21:19:28.430Z,1429651168.430 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0 2015-04-21T21:19:28.431Z,1429651168.431 [SyncHandler](INFO): Protected caller Thread ID is 770 2015-04-21T21:19:28.431Z,1429651168.431 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2015-04-21T21:19:28.433Z,1429651168.433 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0 2015-04-21T21:19:28.433Z,1429651168.433 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 771 2015-04-21T21:19:28.436Z,1429651168.436 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2015-04-21T21:19:28.448Z,1429651168.448 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2015-04-21T21:19:28.453Z,1429651168.453 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0 2015-04-21T21:19:28.453Z,1429651168.453 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 772 2015-04-21T21:19:28.454Z,1429651168.454 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2015-04-21T21:19:28.457Z,1429651168.457 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0 2015-04-21T21:19:28.457Z,1429651168.457 [logger ThreadHandler](INFO): Protected caller Thread ID is 773 2015-04-21T21:19:28.459Z,1429651168.459 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2015-04-21T21:19:28.460Z,1429651168.460 [Supervisor](INFO): Looking for Config files in directory: Config/ 2015-04-21T21:19:28.464Z,1429651168.464 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2015-04-21T21:19:28.835Z,1429651168.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2015-04-21T21:19:28.836Z,1429651168.836 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2015-04-21T21:19:29.023Z,1429651169.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2015-04-21T21:19:29.025Z,1429651169.025 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2015-04-21T21:19:29.151Z,1429651169.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2015-04-21T21:19:29.152Z,1429651169.152 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2015-04-21T21:19:29.291Z,1429651169.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2015-04-21T21:19:29.292Z,1429651169.292 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2015-04-21T21:19:29.390Z,1429651169.390 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2015-04-21T21:19:29.572Z,1429651169.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2015-04-21T21:19:29.572Z,1429651169.572 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2015-04-21T21:19:29.670Z,1429651169.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2015-04-21T21:19:29.671Z,1429651169.671 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2015-04-21T21:19:29.918Z,1429651169.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2015-04-21T21:19:29.919Z,1429651169.919 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2015-04-21T21:19:30.321Z,1429651170.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2015-04-21T21:19:30.322Z,1429651170.322 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2015-04-21T21:19:30.656Z,1429651170.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2015-04-21T21:19:30.658Z,1429651170.658 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2015-04-21T21:19:31.227Z,1429651171.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2015-04-21T21:19:31.228Z,1429651171.228 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2015-04-21T21:19:31.438Z,1429651171.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2015-04-21T21:19:31.439Z,1429651171.439 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2015-04-21T21:19:31.558Z,1429651171.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2015-04-21T21:19:31.559Z,1429651171.559 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2015-04-21T21:19:32.039Z,1429651172.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2015-04-21T21:19:32.039Z,1429651172.039 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2015-04-21T21:19:32.232Z,1429651172.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2015-04-21T21:19:32.234Z,1429651172.234 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2015-04-21T21:19:32.237Z,1429651172.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2015-04-21T21:19:32.352Z,1429651172.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2015-04-21T21:19:32.488Z,1429651172.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2015-04-21T21:19:32.601Z,1429651172.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg 2015-04-21T21:19:32.723Z,1429651172.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2015-04-21T21:19:32.829Z,1429651172.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2015-04-21T21:19:32.973Z,1429651172.973 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2015-04-21T21:19:33.142Z,1429651173.142 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2015-04-21T21:19:33.254Z,1429651173.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2015-04-21T21:19:33.354Z,1429651173.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2015-04-21T21:19:33.444Z,1429651173.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2015-04-21T21:19:33.552Z,1429651173.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2015-04-21T21:19:33.681Z,1429651173.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2015-04-21T21:19:33.951Z,1429651173.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2015-04-21T21:19:33.951Z,1429651173.951 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2015-04-21T21:19:33.957Z,1429651173.957 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2015-04-21T21:19:34.034Z,1429651174.034 [VerticalControl](DEBUG): Construct VerticalControl. 2015-04-21T21:19:34.171Z,1429651174.171 [VerticalControl] Loaded 2015-04-21T21:19:34.171Z,1429651174.171 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2015-04-21T21:19:34.172Z,1429651174.172 [HorizontalControl](DEBUG): Construct HorizontalControl. 2015-04-21T21:19:34.259Z,1429651174.259 [HorizontalControl] Loaded 2015-04-21T21:19:34.259Z,1429651174.259 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2015-04-21T21:19:34.260Z,1429651174.260 [SpeedControl](DEBUG): Construct SpeedControl. 2015-04-21T21:19:34.266Z,1429651174.266 [SpeedControl] Loaded 2015-04-21T21:19:34.267Z,1429651174.267 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2015-04-21T21:19:34.267Z,1429651174.267 [LoopControl](DEBUG): Construct LoopControl. 2015-04-21T21:19:34.268Z,1429651174.268 [LoopControl] Loaded 2015-04-21T21:19:34.268Z,1429651174.268 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2015-04-21T21:19:34.269Z,1429651174.269 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2015-04-21T21:19:34.269Z,1429651174.269 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2015-04-21T21:19:34.435Z,1429651174.435 [SBIT](DEBUG): Construct Startup Built In Test. 2015-04-21T21:19:34.447Z,1429651174.447 [SBIT] Loaded 2015-04-21T21:19:34.447Z,1429651174.447 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2015-04-21T21:19:34.448Z,1429651174.448 [IBIT](DEBUG): Construct Initiated Built In Test. 2015-04-21T21:19:34.482Z,1429651174.482 [IBIT] Loaded 2015-04-21T21:19:34.483Z,1429651174.483 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2015-04-21T21:19:34.485Z,1429651174.485 [CBIT](DEBUG): Construct Continuous Built In Test. 2015-04-21T21:19:34.643Z,1429651174.643 [CBIT] Loaded 2015-04-21T21:19:34.643Z,1429651174.643 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2015-04-21T21:19:34.644Z,1429651174.644 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2015-04-21T21:19:34.644Z,1429651174.644 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2015-04-21T21:19:34.681Z,1429651174.681 [DepthRateCalculator] Loaded 2015-04-21T21:19:34.681Z,1429651174.681 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2015-04-21T21:19:34.687Z,1429651174.687 [PitchRateCalculator] Loaded 2015-04-21T21:19:34.687Z,1429651174.687 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2015-04-21T21:19:34.699Z,1429651174.699 [SpeedCalculator] Loaded 2015-04-21T21:19:34.699Z,1429651174.699 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2015-04-21T21:19:34.716Z,1429651174.716 [TempGradientCalculator] Loaded 2015-04-21T21:19:34.717Z,1429651174.717 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2015-04-21T21:19:34.722Z,1429651174.722 [YawRateCalculator] Loaded 2015-04-21T21:19:34.723Z,1429651174.723 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2015-04-21T21:19:34.723Z,1429651174.723 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2015-04-21T21:19:34.724Z,1429651174.724 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2015-04-21T21:19:39.410Z,1429651179.410 [HFRadarCompactModelForecaster] Loaded 2015-04-21T21:19:39.410Z,1429651179.410 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread. 2015-04-21T21:19:40.504Z,1429651180.504 [HFRCMSpaceInterpolator] Loaded 2015-04-21T21:19:40.504Z,1429651180.504 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread. 2015-04-21T21:19:41.743Z,1429651181.743 [HFRCMReconstructedInterpolator] Loaded 2015-04-21T21:19:41.744Z,1429651181.744 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread. 2015-04-21T21:19:41.756Z,1429651181.756 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded 2015-04-21T21:19:41.757Z,1429651181.757 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread. 2015-04-21T21:19:41.790Z,1429651181.790 [HFRCMVirtualSurfaceDrifter] Loaded 2015-04-21T21:19:41.790Z,1429651181.790 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread. 2015-04-21T21:19:41.791Z,1429651181.791 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2015-04-21T21:19:41.791Z,1429651181.791 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2015-04-21T21:19:42.097Z,1429651182.097 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2015-04-21T21:19:42.097Z,1429651182.097 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2015-04-21T21:19:42.217Z,1429651182.217 [DeadReckonUsingMultipleVelocitySources] Loaded 2015-04-21T21:19:42.217Z,1429651182.217 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2015-04-21T21:19:42.269Z,1429651182.269 [DeadReckonUsingSpeedCalculator] Loaded 2015-04-21T21:19:42.269Z,1429651182.269 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2015-04-21T21:19:42.292Z,1429651182.292 [NavChart] Loaded 2015-04-21T21:19:42.292Z,1429651182.292 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2015-04-21T21:19:42.296Z,1429651182.296 [UniversalFixResidualReporter] Loaded 2015-04-21T21:19:42.297Z,1429651182.297 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2015-04-21T21:19:42.297Z,1429651182.297 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2015-04-21T21:19:42.298Z,1429651182.298 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2015-04-21T21:19:42.307Z,1429651182.307 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2015-04-21T21:19:42.308Z,1429651182.308 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2015-04-21T21:19:42.523Z,1429651182.523 [Aanderaa_O2] Loaded 2015-04-21T21:19:42.523Z,1429651182.523 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2015-04-21T21:19:42.611Z,1429651182.611 [CTD_NeilBrown] Loaded 2015-04-21T21:19:42.611Z,1429651182.611 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2015-04-21T21:19:42.615Z,1429651182.615 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406E94E0 2015-04-21T21:19:42.615Z,1429651182.615 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 858 2015-04-21T21:19:42.629Z,1429651182.629 [ISUS] Loaded 2015-04-21T21:19:42.629Z,1429651182.629 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread. 2015-04-21T21:19:42.648Z,1429651182.648 [PAR_Licor] Loaded 2015-04-21T21:19:42.648Z,1429651182.648 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2015-04-21T21:19:42.702Z,1429651182.702 [WetLabsBB2FL] Loaded 2015-04-21T21:19:42.702Z,1429651182.702 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2015-04-21T21:19:42.707Z,1429651182.707 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407194E0 2015-04-21T21:19:42.707Z,1429651182.707 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 859 2015-04-21T21:19:42.708Z,1429651182.708 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2015-04-21T21:19:42.709Z,1429651182.709 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2015-04-21T21:19:43.452Z,1429651183.452 [AHRS_sp3003D] Loaded 2015-04-21T21:19:43.452Z,1429651183.452 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2015-04-21T21:19:43.639Z,1429651183.639 [DataOverHttps] Loaded 2015-04-21T21:19:43.640Z,1429651183.640 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2015-04-21T21:19:43.654Z,1429651183.654 [Depth_Keller] Loaded 2015-04-21T21:19:43.654Z,1429651183.654 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2015-04-21T21:19:43.659Z,1429651183.659 [DropWeight] Loaded 2015-04-21T21:19:43.659Z,1429651183.659 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2015-04-21T21:19:43.817Z,1429651183.817 [DVL_micro] Loaded 2015-04-21T21:19:43.817Z,1429651183.817 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2015-04-21T21:19:43.928Z,1429651183.928 [NAL9602] Loaded 2015-04-21T21:19:43.929Z,1429651183.929 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2015-04-21T21:19:43.977Z,1429651183.977 [Onboard] Loaded 2015-04-21T21:19:43.978Z,1429651183.978 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2015-04-21T21:19:43.985Z,1429651183.985 [Radio_Surface] Loaded 2015-04-21T21:19:43.985Z,1429651183.985 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2015-04-21T21:19:43.989Z,1429651183.989 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0 2015-04-21T21:19:43.989Z,1429651183.989 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 860 2015-04-21T21:19:44.147Z,1429651184.147 [DAT] Loaded 2015-04-21T21:19:44.147Z,1429651184.147 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2015-04-21T21:19:46.305Z,1429651186.305 [BPC1] Loaded 2015-04-21T21:19:46.305Z,1429651186.305 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2015-04-21T21:19:46.306Z,1429651186.306 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2015-04-21T21:19:46.306Z,1429651186.306 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2015-04-21T21:19:46.443Z,1429651186.443 [BuoyancyServo] Loaded 2015-04-21T21:19:46.444Z,1429651186.444 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2015-04-21T21:19:46.461Z,1429651186.461 [ElevatorServo] Loaded 2015-04-21T21:19:46.461Z,1429651186.461 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2015-04-21T21:19:46.478Z,1429651186.478 [RudderServo] Loaded 2015-04-21T21:19:46.478Z,1429651186.478 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2015-04-21T21:19:46.490Z,1429651186.490 [ThrusterServo] Loaded 2015-04-21T21:19:46.491Z,1429651186.491 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2015-04-21T21:19:46.491Z,1429651186.491 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2015-04-21T21:19:46.492Z,1429651186.492 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2015-04-21T21:19:46.644Z,1429651186.644 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2015-04-21T21:19:46.645Z,1429651186.645 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2015-04-21T21:19:46.688Z,1429651186.688 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2015-04-21T21:19:46.692Z,1429651186.692 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2015-04-21T21:19:46.693Z,1429651186.693 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2015-04-21T21:19:46.700Z,1429651186.700 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2015-04-21T21:19:46.701Z,1429651186.701 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0 2015-04-21T21:19:46.701Z,1429651186.701 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 861 2015-04-21T21:19:46.706Z,1429651186.706 [Supervisor](INFO): Main Thread ID is 765 2015-04-21T21:19:46.706Z,1429651186.706 [Supervisor](DEBUG): Running supervisor. 2015-04-21T21:19:46.707Z,1429651186.707 [CommandLine ThreadHandler](INFO): Handler Thread ID is 862 2015-04-21T21:19:46.714Z,1429651186.714 [controlThread ThreadHandler](INFO): Handler Thread ID is 863 2015-04-21T21:19:46.714Z,1429651186.714 [controlThread](DEBUG): Initializing ControlThread 2015-04-21T21:19:46.715Z,1429651186.715 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2015-04-21T21:19:46.717Z,1429651186.717 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2015-04-21T21:19:46.717Z,1429651186.717 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2015-04-21T21:19:46.718Z,1429651186.718 [LoopControl](DEBUG): Initialize LoopControlComponent. 2015-04-21T21:19:46.718Z,1429651186.718 [SBIT](INFO): Initialize SBIT Component. 2015-04-21T21:19:46.719Z,1429651186.719 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12129 2015-04-21T21:19:46.719Z,1429651186.719 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2015-04-21T21:19:46.719Z,1429651186.719 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2015-04-21T21:19:46.720Z,1429651186.720 [IBIT](INFO): Initialize IBIT Component. 2015-04-21T21:19:46.721Z,1429651186.721 [CBIT](DEBUG): Initialize CBIT Component. 2015-04-21T21:19:46.721Z,1429651186.721 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2015-04-21T21:19:46.721Z,1429651186.721 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2015-04-21T21:19:46.726Z,1429651186.726 [logger ThreadHandler](INFO): Handler Thread ID is 864 2015-04-21T21:19:46.745Z,1429651186.745 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2015-04-21T21:19:46.746Z,1429651186.746 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2015-04-21T21:19:46.746Z,1429651186.746 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2015-04-21T21:19:46.746Z,1429651186.746 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2015-04-21T21:19:46.747Z,1429651186.747 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2015-04-21T21:19:46.762Z,1429651186.762 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 865 2015-04-21T21:19:46.764Z,1429651186.764 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2015-04-21T21:19:46.766Z,1429651186.766 [HFRadarCompactModelForecaster](DEBUG): Found new ECs! 2015-04-21T21:19:46.766Z,1429651186.766 [HFRadarCompactModelForecaster](ERROR): Most recent ECs are more than six hours old. 2015-04-21T21:19:46.768Z,1429651186.768 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2015-04-21T21:19:46.801Z,1429651186.801 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 867 2015-04-21T21:19:46.805Z,1429651186.805 [WetLabsBB2FL](INFO): Powering down 2015-04-21T21:19:46.828Z,1429651186.828 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 868 2015-04-21T21:19:46.844Z,1429651186.844 [Radio_Surface](INFO): Powering up 2015-04-21T21:19:46.874Z,1429651186.874 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 869 2015-04-21T21:19:46.882Z,1429651186.882 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2015-04-21T21:19:46.883Z,1429651186.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2015-04-21T21:19:46.883Z,1429651186.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2015-04-21T21:19:46.883Z,1429651186.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2015-04-21T21:19:46.884Z,1429651186.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2015-04-21T21:19:46.884Z,1429651186.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2015-04-21T21:19:46.884Z,1429651186.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2015-04-21T21:19:46.884Z,1429651186.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2015-04-21T21:19:46.885Z,1429651186.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2015-04-21T21:19:46.914Z,1429651186.914 [CTD_NeilBrown](DEBUG): CTD uart timeout: serial timeout 2015-04-21T21:19:46.914Z,1429651186.914 [CTD_NeilBrown](ERROR): Could not get real or simulated CTD data. startTime.elapsed() = 0.145730 2015-04-21T21:19:56.450Z,1429651196.450 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201504131600 published 2015-04-21T21:19:56.461Z,1429651196.461 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 1. 2015-04-21T21:19:56.463Z,1429651196.463 [HFRCMSpaceInterpolator](INFO): gridIdxRev_: 541 by 1, [25.000000, 718.000000] 2015-04-21T21:19:56.464Z,1429651196.464 [HFRCMSpaceInterpolator](INFO): gridIdx_: 25 by 29, [nan, nan] 2015-04-21T21:19:56.465Z,1429651196.465 [HFRCMSpaceInterpolator](INFO): longitude grid: 25 by 29, [-122.743156, -121.797913] 2015-04-21T21:19:56.465Z,1429651196.465 [HFRCMSpaceInterpolator](INFO): latitude grid: 25 by 29, [36.341251, 36.990059] 2015-04-21T21:19:56.473Z,1429651196.473 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s. 2015-04-21T21:19:56.474Z,1429651196.474 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component. 2015-04-21T21:19:56.474Z,1429651196.474 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component. 2015-04-21T21:19:56.475Z,1429651196.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2015-04-21T21:19:56.475Z,1429651196.475 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2015-04-21T21:19:56.476Z,1429651196.476 [NavChart](DEBUG): Initialize NavChart Navigation. 2015-04-21T21:19:56.484Z,1429651196.484 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2015-04-21T21:19:56.496Z,1429651196.496 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2015-04-21T21:19:56.567Z,1429651196.567 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-04-21T21:19:56.628Z,1429651196.628 [MissionManager](DEBUG): 2015-04-21T21:19:56.629Z,1429651196.629 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2015-04-21T21:19:56.741Z,1429651196.741 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2015-04-21T21:19:56.742Z,1429651196.742 [Default:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-04-21T21:19:56.759Z,1429651196.759 [Default:CheckIn:A.SetSpeed](DEBUG): Construct. 2015-04-21T21:19:56.797Z,1429651196.797 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2015-04-21T21:19:56.800Z,1429651196.800 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct. 2015-04-21T21:19:56.822Z,1429651196.822 [MissionManager](DEBUG): 0 5.0 1.0 400 10 500 Default mission has been running for Burn 300 Dropped weight due to communications timeout. 5 2015-04-21T21:19:56.843Z,1429651196.843 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ISUS,PAR_Licor,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2015-04-21T21:19:57.005Z,1429651197.005 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2015-04-21T21:19:57.557Z,1429651197.557 [DVL_micro](INFO): Initializing 2015-04-21T21:19:57.589Z,1429651197.589 [NAL9602](INFO): Powering up NAL9602 2015-04-21T21:19:57.810Z,1429651197.810 [DAT](INFO): Powering up 2015-04-21T21:19:57.811Z,1429651197.811 [DAT](DEBUG): Initializing DAT. 2015-04-21T21:20:07.538Z,1429651207.538 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: address not mapped to object 2015-04-21T21:20:07.538Z,1429651207.538 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x360 2015-04-21T21:20:07.538Z,1429651207.538 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.538Z,1429651207.538 [HFRCMSurfaceCurrentAtVehicleLocation] Software Fault, FailCount= 1 2015-04-21T21:20:07.539Z,1429651207.539 [HFRCMSurfaceCurrentAtVehicleLocation](ERROR): Software Fault 2015-04-21T21:20:07.548Z,1429651207.548 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.548Z,1429651207.548 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.548Z,1429651207.548 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.568Z,1429651207.569 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.569Z,1429651207.569 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.569Z,1429651207.569 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.590Z,1429651207.590 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.590Z,1429651207.590 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.590Z,1429651207.590 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.595Z,1429651207.595 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.595Z,1429651207.595 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.596Z,1429651207.596 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.610Z,1429651207.610 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.610Z,1429651207.610 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.610Z,1429651207.610 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.622Z,1429651207.622 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.622Z,1429651207.622 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.622Z,1429651207.622 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.634Z,1429651207.634 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.634Z,1429651207.634 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.634Z,1429651207.634 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.646Z,1429651207.646 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.647Z,1429651207.647 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.647Z,1429651207.647 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.673Z,1429651207.673 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.673Z,1429651207.673 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.673Z,1429651207.673 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.682Z,1429651207.682 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.683Z,1429651207.683 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.683Z,1429651207.683 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.698Z,1429651207.698 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.698Z,1429651207.698 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.699Z,1429651207.699 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.710Z,1429651207.710 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.710Z,1429651207.710 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.711Z,1429651207.711 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.722Z,1429651207.722 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.722Z,1429651207.722 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.722Z,1429651207.722 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.734Z,1429651207.734 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.735Z,1429651207.735 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.735Z,1429651207.735 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.747Z,1429651207.747 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.747Z,1429651207.747 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.748Z,1429651207.748 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.758Z,1429651207.758 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.758Z,1429651207.758 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.758Z,1429651207.758 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.771Z,1429651207.771 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.771Z,1429651207.771 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.771Z,1429651207.771 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.786Z,1429651207.786 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object 2015-04-21T21:20:07.786Z,1429651207.786 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000 2015-04-21T21:20:07.787Z,1429651207.787 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c] /lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510] 2015-04-21T21:20:07.798Z,1429651207