2015-04-21T21:04:17.587Z,1429650257.587 [Supervisor](DEBUG): Initializing supervisor.
2015-04-21T21:04:17.589Z,1429650257.589 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-04-21T21:04:17.590Z,1429650257.590 [SyncHandler](INFO): Protected caller Thread ID is 888
2015-04-21T21:04:17.590Z,1429650257.590 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-04-21T21:04:17.591Z,1429650257.591 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-04-21T21:04:17.592Z,1429650257.592 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 889
2015-04-21T21:04:17.594Z,1429650257.594 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-04-21T21:04:17.606Z,1429650257.606 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-04-21T21:04:17.607Z,1429650257.607 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-04-21T21:04:17.607Z,1429650257.607 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 890
2015-04-21T21:04:17.608Z,1429650257.608 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-04-21T21:04:17.609Z,1429650257.609 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-04-21T21:04:17.610Z,1429650257.610 [logger ThreadHandler](INFO): Protected caller Thread ID is 891
2015-04-21T21:04:17.612Z,1429650257.612 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-04-21T21:04:17.612Z,1429650257.612 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-04-21T21:04:17.614Z,1429650257.614 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-04-21T21:04:17.937Z,1429650257.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-04-21T21:04:17.938Z,1429650257.938 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-04-21T21:04:18.101Z,1429650258.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-04-21T21:04:18.102Z,1429650258.102 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-04-21T21:04:18.210Z,1429650258.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-04-21T21:04:18.210Z,1429650258.210 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-04-21T21:04:18.334Z,1429650258.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-04-21T21:04:18.334Z,1429650258.334 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-04-21T21:04:18.419Z,1429650258.419 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-04-21T21:04:18.578Z,1429650258.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-04-21T21:04:18.578Z,1429650258.578 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-04-21T21:04:18.663Z,1429650258.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-04-21T21:04:18.664Z,1429650258.664 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-04-21T21:04:18.887Z,1429650258.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-04-21T21:04:18.887Z,1429650258.887 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-04-21T21:04:19.242Z,1429650259.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-04-21T21:04:19.243Z,1429650259.243 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-04-21T21:04:19.540Z,1429650259.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-04-21T21:04:19.540Z,1429650259.540 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-04-21T21:04:20.031Z,1429650260.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-04-21T21:04:20.032Z,1429650260.032 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-04-21T21:04:20.214Z,1429650260.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-04-21T21:04:20.214Z,1429650260.214 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-04-21T21:04:20.317Z,1429650260.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-04-21T21:04:20.318Z,1429650260.318 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-04-21T21:04:20.738Z,1429650260.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-04-21T21:04:20.739Z,1429650260.739 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-04-21T21:04:20.849Z,1429650260.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-04-21T21:04:20.851Z,1429650260.851 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2015-04-21T21:04:20.852Z,1429650260.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2015-04-21T21:04:20.954Z,1429650260.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2015-04-21T21:04:21.072Z,1429650261.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2015-04-21T21:04:21.173Z,1429650261.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2015-04-21T21:04:21.281Z,1429650261.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2015-04-21T21:04:21.375Z,1429650261.375 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2015-04-21T21:04:21.502Z,1429650261.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2015-04-21T21:04:21.650Z,1429650261.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2015-04-21T21:04:21.750Z,1429650261.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2015-04-21T21:04:21.839Z,1429650261.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2015-04-21T21:04:21.919Z,1429650261.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2015-04-21T21:04:22.015Z,1429650262.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2015-04-21T21:04:22.128Z,1429650262.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2015-04-21T21:04:22.371Z,1429650262.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-04-21T21:04:22.372Z,1429650262.372 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-04-21T21:04:22.373Z,1429650262.373 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-04-21T21:04:22.433Z,1429650262.433 [VerticalControl](DEBUG): Construct VerticalControl.
2015-04-21T21:04:22.554Z,1429650262.554 [VerticalControl] Loaded
2015-04-21T21:04:22.555Z,1429650262.555 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-04-21T21:04:22.555Z,1429650262.555 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-04-21T21:04:22.629Z,1429650262.629 [HorizontalControl] Loaded
2015-04-21T21:04:22.629Z,1429650262.629 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-04-21T21:04:22.630Z,1429650262.630 [SpeedControl](DEBUG): Construct SpeedControl.
2015-04-21T21:04:22.636Z,1429650262.636 [SpeedControl] Loaded
2015-04-21T21:04:22.636Z,1429650262.636 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-04-21T21:04:22.637Z,1429650262.637 [LoopControl](DEBUG): Construct LoopControl.
2015-04-21T21:04:22.638Z,1429650262.638 [LoopControl] Loaded
2015-04-21T21:04:22.638Z,1429650262.638 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-04-21T21:04:22.638Z,1429650262.638 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-04-21T21:04:22.639Z,1429650262.639 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-04-21T21:04:22.764Z,1429650262.764 [SBIT](DEBUG): Construct Startup Built In Test.
2015-04-21T21:04:22.775Z,1429650262.775 [SBIT] Loaded
2015-04-21T21:04:22.776Z,1429650262.776 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-04-21T21:04:22.776Z,1429650262.776 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-04-21T21:04:22.806Z,1429650262.806 [IBIT] Loaded
2015-04-21T21:04:22.806Z,1429650262.806 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-04-21T21:04:22.808Z,1429650262.808 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-04-21T21:04:22.948Z,1429650262.948 [CBIT] Loaded
2015-04-21T21:04:22.948Z,1429650262.948 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-04-21T21:04:22.948Z,1429650262.948 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-04-21T21:04:22.949Z,1429650262.949 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-04-21T21:04:22.968Z,1429650262.968 [DepthRateCalculator] Loaded
2015-04-21T21:04:22.968Z,1429650262.968 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-04-21T21:04:22.974Z,1429650262.974 [PitchRateCalculator] Loaded
2015-04-21T21:04:22.974Z,1429650262.974 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-04-21T21:04:22.985Z,1429650262.985 [SpeedCalculator] Loaded
2015-04-21T21:04:22.986Z,1429650262.986 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-04-21T21:04:23.002Z,1429650263.002 [TempGradientCalculator] Loaded
2015-04-21T21:04:23.003Z,1429650263.003 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-04-21T21:04:23.008Z,1429650263.008 [YawRateCalculator] Loaded
2015-04-21T21:04:23.008Z,1429650263.008 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-04-21T21:04:23.009Z,1429650263.009 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-04-21T21:04:23.010Z,1429650263.010 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-04-21T21:04:27.244Z,1429650267.244 [HFRadarCompactModelForecaster] Loaded
2015-04-21T21:04:27.244Z,1429650267.244 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2015-04-21T21:04:28.343Z,1429650268.343 [HFRCMSpaceInterpolator] Loaded
2015-04-21T21:04:28.343Z,1429650268.343 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2015-04-21T21:04:29.432Z,1429650269.432 [HFRCMReconstructedInterpolator] Loaded
2015-04-21T21:04:29.432Z,1429650269.432 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2015-04-21T21:04:29.444Z,1429650269.444 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2015-04-21T21:04:29.444Z,1429650269.444 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2015-04-21T21:04:29.472Z,1429650269.472 [HFRCMVirtualSurfaceDrifter] Loaded
2015-04-21T21:04:29.472Z,1429650269.472 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2015-04-21T21:04:29.473Z,1429650269.473 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-04-21T21:04:29.473Z,1429650269.473 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-04-21T21:04:29.710Z,1429650269.710 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-04-21T21:04:29.710Z,1429650269.710 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-04-21T21:04:29.799Z,1429650269.799 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-04-21T21:04:29.799Z,1429650269.799 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-04-21T21:04:29.846Z,1429650269.846 [DeadReckonUsingSpeedCalculator] Loaded
2015-04-21T21:04:29.846Z,1429650269.846 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-04-21T21:04:29.864Z,1429650269.864 [NavChart] Loaded
2015-04-21T21:04:29.864Z,1429650269.864 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-04-21T21:04:29.869Z,1429650269.869 [UniversalFixResidualReporter] Loaded
2015-04-21T21:04:29.869Z,1429650269.869 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-04-21T21:04:29.869Z,1429650269.869 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-04-21T21:04:29.870Z,1429650269.870 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-04-21T21:04:29.876Z,1429650269.876 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-04-21T21:04:29.877Z,1429650269.877 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-04-21T21:04:30.052Z,1429650270.052 [Aanderaa_O2] Loaded
2015-04-21T21:04:30.052Z,1429650270.052 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2015-04-21T21:04:30.131Z,1429650270.131 [CTD_NeilBrown] Loaded
2015-04-21T21:04:30.131Z,1429650270.131 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-04-21T21:04:30.132Z,1429650270.132 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406E94E0
2015-04-21T21:04:30.133Z,1429650270.133 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 977
2015-04-21T21:04:30.146Z,1429650270.146 [ISUS] Loaded
2015-04-21T21:04:30.146Z,1429650270.146 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2015-04-21T21:04:30.161Z,1429650270.161 [PAR_Licor] Loaded
2015-04-21T21:04:30.162Z,1429650270.162 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-04-21T21:04:30.211Z,1429650270.211 [WetLabsBB2FL] Loaded
2015-04-21T21:04:30.211Z,1429650270.211 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-04-21T21:04:30.212Z,1429650270.212 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407194E0
2015-04-21T21:04:30.213Z,1429650270.213 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 978
2015-04-21T21:04:30.213Z,1429650270.213 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-04-21T21:04:30.214Z,1429650270.214 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-04-21T21:04:30.495Z,1429650270.495 [AHRS_sp3003D] Loaded
2015-04-21T21:04:30.496Z,1429650270.496 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2015-04-21T21:04:30.568Z,1429650270.568 [DataOverHttps] Loaded
2015-04-21T21:04:30.568Z,1429650270.568 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-04-21T21:04:30.582Z,1429650270.582 [Depth_Keller] Loaded
2015-04-21T21:04:30.582Z,1429650270.582 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-04-21T21:04:30.587Z,1429650270.587 [DropWeight] Loaded
2015-04-21T21:04:30.587Z,1429650270.587 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-04-21T21:04:30.728Z,1429650270.728 [DVL_micro] Loaded
2015-04-21T21:04:30.728Z,1429650270.728 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2015-04-21T21:04:30.826Z,1429650270.826 [NAL9602] Loaded
2015-04-21T21:04:30.827Z,1429650270.827 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-04-21T21:04:30.870Z,1429650270.870 [Onboard] Loaded
2015-04-21T21:04:30.870Z,1429650270.870 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-04-21T21:04:30.877Z,1429650270.877 [Radio_Surface] Loaded
2015-04-21T21:04:30.878Z,1429650270.878 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-04-21T21:04:30.879Z,1429650270.879 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0
2015-04-21T21:04:30.879Z,1429650270.879 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 979
2015-04-21T21:04:31.019Z,1429650271.019 [DAT] Loaded
2015-04-21T21:04:31.019Z,1429650271.019 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2015-04-21T21:04:32.959Z,1429650272.959 [BPC1] Loaded
2015-04-21T21:04:32.959Z,1429650272.959 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-04-21T21:04:32.960Z,1429650272.960 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-04-21T21:04:32.960Z,1429650272.960 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-04-21T21:04:33.063Z,1429650273.063 [BuoyancyServo] Loaded
2015-04-21T21:04:33.064Z,1429650273.064 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-04-21T21:04:33.076Z,1429650273.076 [ElevatorServo] Loaded
2015-04-21T21:04:33.077Z,1429650273.077 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-04-21T21:04:33.089Z,1429650273.089 [RudderServo] Loaded
2015-04-21T21:04:33.089Z,1429650273.089 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-04-21T21:04:33.101Z,1429650273.101 [ThrusterServo] Loaded
2015-04-21T21:04:33.101Z,1429650273.101 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-04-21T21:04:33.102Z,1429650273.102 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-04-21T21:04:33.102Z,1429650273.102 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-04-21T21:04:33.213Z,1429650273.213 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-04-21T21:04:33.214Z,1429650273.214 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-04-21T21:04:33.238Z,1429650273.238 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-04-21T21:04:33.242Z,1429650273.242 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-04-21T21:04:33.243Z,1429650273.243 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-04-21T21:04:33.250Z,1429650273.250 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-04-21T21:04:33.251Z,1429650273.251 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0
2015-04-21T21:04:33.251Z,1429650273.251 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 980
2015-04-21T21:04:33.256Z,1429650273.256 [Supervisor](INFO): Main Thread ID is 887
2015-04-21T21:04:33.256Z,1429650273.256 [Supervisor](DEBUG): Running supervisor.
2015-04-21T21:04:33.257Z,1429650273.257 [CommandLine ThreadHandler](INFO): Handler Thread ID is 981
2015-04-21T21:04:33.260Z,1429650273.260 [controlThread ThreadHandler](INFO): Handler Thread ID is 982
2015-04-21T21:04:33.260Z,1429650273.260 [controlThread](DEBUG): Initializing ControlThread
2015-04-21T21:04:33.261Z,1429650273.261 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-04-21T21:04:33.263Z,1429650273.263 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-04-21T21:04:33.264Z,1429650273.264 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-04-21T21:04:33.264Z,1429650273.264 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-04-21T21:04:33.264Z,1429650273.264 [SBIT](INFO): Initialize SBIT Component.
2015-04-21T21:04:33.265Z,1429650273.265 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12129
2015-04-21T21:04:33.265Z,1429650273.265 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-04-21T21:04:33.265Z,1429650273.265 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-04-21T21:04:33.266Z,1429650273.266 [IBIT](INFO): Initialize IBIT Component.
2015-04-21T21:04:33.267Z,1429650273.267 [CBIT](DEBUG): Initialize CBIT Component.
2015-04-21T21:04:33.267Z,1429650273.267 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-04-21T21:04:33.267Z,1429650273.267 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-04-21T21:04:33.268Z,1429650273.268 [logger ThreadHandler](INFO): Handler Thread ID is 983
2015-04-21T21:04:33.293Z,1429650273.293 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-04-21T21:04:33.293Z,1429650273.293 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-04-21T21:04:33.294Z,1429650273.294 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-04-21T21:04:33.294Z,1429650273.294 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-04-21T21:04:33.295Z,1429650273.295 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-04-21T21:04:33.301Z,1429650273.301 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2015-04-21T21:04:33.302Z,1429650273.302 [HFRadarCompactModelForecaster](ERROR): Most recent ECs are more than six hours old.
2015-04-21T21:04:33.317Z,1429650273.317 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 984
2015-04-21T21:04:33.318Z,1429650273.318 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2015-04-21T21:04:33.325Z,1429650273.325 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2015-04-21T21:04:33.336Z,1429650273.336 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 986
2015-04-21T21:04:33.337Z,1429650273.337 [WetLabsBB2FL](INFO): Powering down
2015-04-21T21:04:33.374Z,1429650273.374 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 987
2015-04-21T21:04:33.381Z,1429650273.381 [Radio_Surface](INFO): Powering up
2015-04-21T21:04:33.397Z,1429650273.397 [CTD_NeilBrown](DEBUG): CTD uart timeout: serial timeout
2015-04-21T21:04:33.398Z,1429650273.398 [CTD_NeilBrown](ERROR): Could not get real or simulated CTD data. startTime.elapsed() = 0.071612
2015-04-21T21:04:33.414Z,1429650273.414 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 988
2015-04-21T21:04:33.416Z,1429650273.416 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-04-21T21:04:33.417Z,1429650273.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-04-21T21:04:33.418Z,1429650273.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-04-21T21:04:33.418Z,1429650273.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-04-21T21:04:33.418Z,1429650273.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-04-21T21:04:33.418Z,1429650273.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-04-21T21:04:33.419Z,1429650273.419 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-04-21T21:04:33.419Z,1429650273.419 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-04-21T21:04:33.419Z,1429650273.419 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-04-21T21:04:42.437Z,1429650282.437 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201504131600 published
2015-04-21T21:04:42.460Z,1429650282.460 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 1.
2015-04-21T21:04:42.462Z,1429650282.462 [HFRCMSpaceInterpolator](INFO): gridIdxRev_: 541 by 1, [25.000000, 718.000000]
2015-04-21T21:04:42.463Z,1429650282.463 [HFRCMSpaceInterpolator](INFO): gridIdx_: 25 by 29, [nan, nan]
2015-04-21T21:04:42.464Z,1429650282.464 [HFRCMSpaceInterpolator](INFO): longitude grid: 25 by 29, [-122.743156, -121.797913]
2015-04-21T21:04:42.465Z,1429650282.465 [HFRCMSpaceInterpolator](INFO): latitude grid: 25 by 29, [36.341251, 36.990059]
2015-04-21T21:04:42.466Z,1429650282.466 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2015-04-21T21:04:42.479Z,1429650282.479 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2015-04-21T21:04:42.480Z,1429650282.480 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2015-04-21T21:04:42.480Z,1429650282.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-04-21T21:04:42.481Z,1429650282.481 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-04-21T21:04:42.481Z,1429650282.481 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-04-21T21:04:42.481Z,1429650282.481 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-04-21T21:04:42.509Z,1429650282.509 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-04-21T21:04:42.544Z,1429650282.544 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-04-21T21:04:42.570Z,1429650282.570 [MissionManager](DEBUG):
2015-04-21T21:04:42.570Z,1429650282.570 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-04-21T21:04:42.654Z,1429650282.654 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2015-04-21T21:04:42.655Z,1429650282.655 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-04-21T21:04:42.693Z,1429650282.693 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-04-21T21:04:42.721Z,1429650282.721 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-04-21T21:04:42.741Z,1429650282.741 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-04-21T21:04:42.747Z,1429650282.747 [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:04:42.769Z,1429650282.769 [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:04:43.008Z,1429650283.008 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2015-04-21T21:04:43.136Z,1429650283.136 [DVL_micro](INFO): Initializing
2015-04-21T21:04:43.174Z,1429650283.174 [NAL9602](INFO): Powering up NAL9602
2015-04-21T21:04:43.304Z,1429650283.304 [DAT](INFO): Powering up
2015-04-21T21:04:43.305Z,1429650283.305 [DAT](DEBUG): Initializing DAT.
2015-04-21T21:04:52.856Z,1429650292.856 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: address not mapped to object
2015-04-21T21:04:52.856Z,1429650292.856 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x360
2015-04-21T21:04:52.856Z,1429650292.856 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.856Z,1429650292.856 [HFRCMSurfaceCurrentAtVehicleLocation] Software Fault, FailCount= 1
2015-04-21T21:04:52.856Z,1429650292.856 [HFRCMSurfaceCurrentAtVehicleLocation](ERROR): Software Fault
2015-04-21T21:04:52.864Z,1429650292.864 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.864Z,1429650292.864 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.864Z,1429650292.864 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.877Z,1429650292.877 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.877Z,1429650292.877 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.877Z,1429650292.877 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.887Z,1429650292.887 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.888Z,1429650292.888 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.888Z,1429650292.888 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.896Z,1429650292.896 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.896Z,1429650292.896 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.897Z,1429650292.897 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.922Z,1429650292.922 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.923Z,1429650292.923 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.923Z,1429650292.923 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.929Z,1429650292.929 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.929Z,1429650292.929 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.929Z,1429650292.929 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.937Z,1429650292.937 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.937Z,1429650292.937 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.938Z,1429650292.938 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.952Z,1429650292.952 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.952Z,1429650292.952 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.952Z,1429650292.952 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.981Z,1429650292.981 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.981Z,1429650292.981 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.982Z,1429650292.982 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:52.992Z,1429650292.992 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:52.992Z,1429650292.992 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:52.992Z,1429650292.992 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.015Z,1429650293.015 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:53.016Z,1429650293.016 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:53.016Z,1429650293.016 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.032Z,1429650293.032 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:53.032Z,1429650293.032 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:53.032Z,1429650293.032 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.044Z,1429650293.044 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:53.044Z,1429650293.044 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:53.044Z,1429650293.044 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.056Z,1429650293.056 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:53.056Z,1429650293.056 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:53.056Z,1429650293.056 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.068Z,1429650293.068 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:53.068Z,1429650293.068 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:53.068Z,1429650293.068 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.080Z,1429650293.080 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:53.080Z,1429650293.080 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:53.080Z,1429650293.080 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.108Z,1429650293.108 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:53.109Z,1429650293.109 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:53.109Z,1429650293.109 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.136Z,1429650293.136 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): SIGSEGV: invalid permissions for mapped object
2015-04-21T21:04:53.136Z,1429650293.136 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Bad address: 0x40484000
2015-04-21T21:04:53.137Z,1429650293.137 [HFRCMSurfaceCurrentAtVehicleLocation](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f60c]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-04-21T21:04:53.142Z,1429650293