2013-06-03T23:02:56.877Z,1370300576.877 [Supervisor](DEBUG): Initializing supervisor.
2013-06-03T23:02:56.879Z,1370300576.879 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:02:56.880Z,1370300576.880 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-06-03T23:02:56.881Z,1370300576.881 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-06-03T23:02:56.885Z,1370300576.885 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-06-03T23:02:56.895Z,1370300576.895 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-06-03T23:02:56.896Z,1370300576.897 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-06-03T23:02:56.897Z,1370300576.897 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-06-03T23:02:56.898Z,1370300576.898 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-06-03T23:02:56.899Z,1370300576.899 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-06-03T23:02:56.900Z,1370300576.900 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-06-03T23:02:57.189Z,1370300577.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-06-03T23:02:57.190Z,1370300577.190 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-06-03T23:02:57.386Z,1370300577.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-06-03T23:02:57.387Z,1370300577.387 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-06-03T23:02:57.471Z,1370300577.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-06-03T23:02:57.471Z,1370300577.471 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-06-03T23:02:57.613Z,1370300577.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-06-03T23:02:57.613Z,1370300577.613 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-06-03T23:02:57.752Z,1370300577.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-06-03T23:02:57.752Z,1370300577.753 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-06-03T23:02:58.002Z,1370300578.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-06-03T23:02:58.003Z,1370300578.003 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-06-03T23:02:58.174Z,1370300578.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-06-03T23:02:58.174Z,1370300578.174 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-06-03T23:02:58.432Z,1370300578.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-06-03T23:02:58.433Z,1370300578.433 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-06-03T23:02:58.532Z,1370300578.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-06-03T23:02:58.532Z,1370300578.532 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-06-03T23:02:58.935Z,1370300578.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-06-03T23:02:58.936Z,1370300578.936 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-06-03T23:02:59.050Z,1370300579.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-06-03T23:02:59.051Z,1370300579.051 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-06-03T23:02:59.136Z,1370300579.136 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-06-03T23:02:59.137Z,1370300579.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-06-03T23:02:59.242Z,1370300579.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-06-03T23:02:59.376Z,1370300579.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-06-03T23:02:59.467Z,1370300579.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-06-03T23:02:59.571Z,1370300579.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-06-03T23:02:59.670Z,1370300579.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-06-03T23:02:59.815Z,1370300579.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-06-03T23:02:59.908Z,1370300579.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-06-03T23:02:59.996Z,1370300579.996 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/
2013-06-03T23:02:59.996Z,1370300579.996 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-06-03T23:03:00.001Z,1370300580.001 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-06-03T23:03:00.236Z,1370300580.236 [InternalSim] Loaded
2013-06-03T23:03:00.236Z,1370300580.236 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-06-03T23:03:00.237Z,1370300580.237 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-06-03T23:03:00.237Z,1370300580.237 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-06-03T23:03:00.295Z,1370300580.295 [SBIT](DEBUG): Construct Startup Built In Test.
2013-06-03T23:03:00.324Z,1370300580.324 [SBIT] Loaded
2013-06-03T23:03:00.324Z,1370300580.324 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-06-03T23:03:00.325Z,1370300580.325 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-06-03T23:03:00.354Z,1370300580.354 [IBIT] Loaded
2013-06-03T23:03:00.354Z,1370300580.354 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-06-03T23:03:00.357Z,1370300580.357 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-06-03T23:03:00.472Z,1370300580.472 [CBIT] Loaded
2013-06-03T23:03:00.472Z,1370300580.472 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-06-03T23:03:00.473Z,1370300580.473 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-06-03T23:03:00.473Z,1370300580.473 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-06-03T23:03:00.623Z,1370300580.623 [BuoyancyServo] Loaded
2013-06-03T23:03:00.624Z,1370300580.624 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-06-03T23:03:00.636Z,1370300580.636 [ElevatorServo] Loaded
2013-06-03T23:03:00.636Z,1370300580.636 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-06-03T23:03:00.648Z,1370300580.648 [MassServo] Loaded
2013-06-03T23:03:00.649Z,1370300580.648 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-06-03T23:03:00.661Z,1370300580.661 [RudderServo] Loaded
2013-06-03T23:03:00.661Z,1370300580.661 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-06-03T23:03:00.672Z,1370300580.672 [ThrusterServo] Loaded
2013-06-03T23:03:00.673Z,1370300580.673 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-06-03T23:03:00.673Z,1370300580.673 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-06-03T23:03:00.674Z,1370300580.674 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-06-03T23:03:00.690Z,1370300580.690 [DepthRateCalculator] Loaded
2013-06-03T23:03:00.691Z,1370300580.691 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-06-03T23:03:03.560Z,1370300583.560 [HFRadarModelCalc] Loaded
2013-06-03T23:03:03.561Z,1370300583.561 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-06-03T23:03:03.576Z,1370300583.576 [NavChart] Loaded
2013-06-03T23:03:03.577Z,1370300583.577 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-06-03T23:03:03.582Z,1370300583.582 [PitchRateCalculator] Loaded
2013-06-03T23:03:03.583Z,1370300583.583 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-06-03T23:03:03.593Z,1370300583.593 [SpeedCalculator] Loaded
2013-06-03T23:03:03.594Z,1370300583.594 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-06-03T23:03:03.608Z,1370300583.608 [TempGradientCalculator] Loaded
2013-06-03T23:03:03.609Z,1370300583.609 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-06-03T23:03:03.614Z,1370300583.615 [YawRateCalculator] Loaded
2013-06-03T23:03:03.615Z,1370300583.615 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-06-03T23:03:03.660Z,1370300583.659 [Navigation] Loaded
2013-06-03T23:03:03.660Z,1370300583.660 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-06-03T23:03:03.660Z,1370300583.660 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-06-03T23:03:03.661Z,1370300583.661 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-06-03T23:03:03.872Z,1370300583.872 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-06-03T23:03:03.873Z,1370300583.873 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-06-03T23:03:03.895Z,1370300583.895 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-06-03T23:03:03.896Z,1370300583.896 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-06-03T23:03:03.936Z,1370300583.935 [VerticalControl](DEBUG): Construct VerticalControl.
2013-06-03T23:03:04.028Z,1370300584.028 [VerticalControl] Loaded
2013-06-03T23:03:04.028Z,1370300584.028 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-06-03T23:03:04.029Z,1370300584.029 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-06-03T23:03:04.084Z,1370300584.084 [HorizontalControl] Loaded
2013-06-03T23:03:04.084Z,1370300584.084 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-06-03T23:03:04.085Z,1370300584.085 [SpeedControl](DEBUG): Construct SpeedControl.
2013-06-03T23:03:04.087Z,1370300584.087 [SpeedControl] Loaded
2013-06-03T23:03:04.087Z,1370300584.087 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-06-03T23:03:04.088Z,1370300584.088 [LoopControl](DEBUG): Construct LoopControl.
2013-06-03T23:03:04.089Z,1370300584.089 [LoopControl] Loaded
2013-06-03T23:03:04.089Z,1370300584.089 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-06-03T23:03:04.089Z,1370300584.089 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-06-03T23:03:04.090Z,1370300584.090 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-06-03T23:03:04.095Z,1370300584.095 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-06-03T23:03:04.100Z,1370300584.100 [AsyncPiEstimator] Loaded
2013-06-03T23:03:04.100Z,1370300584.100 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-06-03T23:03:04.101Z,1370300584.101 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4063B4E0
2013-06-03T23:03:04.102Z,1370300584.102 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-06-03T23:03:04.103Z,1370300584.103 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-06-03T23:03:04.240Z,1370300584.240 [AHRS_sp3003D] Loaded
2013-06-03T23:03:04.240Z,1370300584.240 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-06-03T23:03:04.491Z,1370300584.491 [Batt_Ocean_Server] Loaded
2013-06-03T23:03:04.492Z,1370300584.492 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-06-03T23:03:04.505Z,1370300584.505 [Depth_Keller] Loaded
2013-06-03T23:03:04.505Z,1370300584.505 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-06-03T23:03:04.511Z,1370300584.511 [DropWeight] Loaded
2013-06-03T23:03:04.511Z,1370300584.511 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-06-03T23:03:04.680Z,1370300584.680 [DVL_micro] Loaded
2013-06-03T23:03:04.680Z,1370300584.680 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-06-03T23:03:04.681Z,1370300584.681 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:03:04.762Z,1370300584.763 [NAL9602] Loaded
2013-06-03T23:03:04.763Z,1370300584.763 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-06-03T23:03:04.812Z,1370300584.812 [Onboard] Loaded
2013-06-03T23:03:04.813Z,1370300584.813 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-06-03T23:03:04.819Z,1370300584.819 [Radio_Freewave] Loaded
2013-06-03T23:03:04.820Z,1370300584.820 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-06-03T23:03:04.975Z,1370300584.975 [DAT] Loaded
2013-06-03T23:03:04.976Z,1370300584.976 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-06-03T23:03:04.983Z,1370300584.983 [SCPI] Loaded
2013-06-03T23:03:04.983Z,1370300584.983 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2013-06-03T23:03:04.983Z,1370300584.983 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-06-03T23:03:04.984Z,1370300584.984 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-06-03T23:03:05.067Z,1370300585.066 [CTD_NeilBrown] Loaded
2013-06-03T23:03:05.067Z,1370300585.067 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-06-03T23:03:05.068Z,1370300585.068 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407154E0
2013-06-03T23:03:05.098Z,1370300585.098 [WetLabsBB2FL] Loaded
2013-06-03T23:03:05.099Z,1370300585.099 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-06-03T23:03:05.100Z,1370300585.100 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407454E0
2013-06-03T23:03:05.100Z,1370300585.100 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-06-03T23:03:05.103Z,1370300585.103 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-06-03T23:03:05.104Z,1370300585.104 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-06-03T23:03:05.110Z,1370300585.110 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-06-03T23:03:05.111Z,1370300585.111 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407754E0
2013-06-03T23:03:05.116Z,1370300585.115 [Supervisor](DEBUG): Running supervisor.
2013-06-03T23:03:05.117Z,1370300585.117 [CommandLine](INFO): Thread ID is 1452
2013-06-03T23:03:05.119Z,1370300585.119 [controlThread](INFO): Thread ID is 1451
2013-06-03T23:03:05.119Z,1370300585.119 [controlThread](DEBUG): Initializing ControlThread
2013-06-03T23:03:05.120Z,1370300585.120 [CycleStarter](INFO): Thread ID is 1450
2013-06-03T23:03:05.120Z,1370300585.120 [InternalSim](DEBUG):  InternalSim initializing...
2013-06-03T23:03:05.155Z,1370300585.155 [logger](INFO): Thread ID is 1453
2013-06-03T23:03:05.180Z,1370300585.180 [SBIT](INFO): Initialize SBIT Component.
2013-06-03T23:03:05.180Z,1370300585.180 [SBIT](IMPORTANT): Tethys CM Info:
$Rev:10401
2013-06-03T23:03:05.181Z,1370300585.181 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-06-03T23:03:05.181Z,1370300585.181 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
2013-06-03T23:03:05.182Z,1370300585.182 [IBIT](INFO): Initialize IBIT Component.
2013-06-03T23:03:05.183Z,1370300585.182 [CBIT](DEBUG): Initialize CBIT Component.
2013-06-03T23:03:05.183Z,1370300585.183 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-06-03T23:03:05.195Z,1370300585.195 [AsyncPiEstimator](INFO): Thread ID is 1514
2013-06-03T23:03:05.195Z,1370300585.195 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-06-03T23:03:05.207Z,1370300585.207 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-06-03T23:03:05.208Z,1370300585.208 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-06-03T23:03:05.208Z,1370300585.208 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-06-03T23:03:05.209Z,1370300585.209 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-06-03T23:03:05.209Z,1370300585.209 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-06-03T23:03:05.211Z,1370300585.211 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-06-03T23:03:05.211Z,1370300585.211 [Navigation](DEBUG): Initializing Navigation.
2013-06-03T23:03:05.212Z,1370300585.212 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-06-03T23:03:05.213Z,1370300585.213 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-06-03T23:03:05.214Z,1370300585.214 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-06-03T23:03:05.214Z,1370300585.214 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-06-03T23:03:05.220Z,1370300585.220 [DVL_micro](INFO): Thread ID is 1515
2013-06-03T23:03:05.229Z,1370300585.229 [DVL_micro](INFO): Initializing
2013-06-03T23:03:05.229Z,1370300585.229 [DVL_micro](INFO): start:Powering up
2013-06-03T23:03:05.230Z,1370300585.230 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-06-03T23:03:05.231Z,1370300585.231 [DVL_micro](INFO): Cycling power to configure device.
2013-06-03T23:03:05.243Z,1370300585.243 [CTD_NeilBrown](INFO): Thread ID is 1516
2013-06-03T23:03:05.244Z,1370300585.244 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-06-03T23:03:05.250Z,1370300585.250 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-06-03T23:03:05.257Z,1370300585.257 [WetLabsBB2FL](INFO): Thread ID is 1517
2013-06-03T23:03:05.257Z,1370300585.257 [WetLabsBB2FL](INFO): Powering down
2013-06-03T23:03:05.293Z,1370300585.293 [NavChartDb](INFO): Thread ID is 1518
2013-06-03T23:03:05.296Z,1370300585.296 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-06-03T23:03:05.297Z,1370300585.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-06-03T23:03:05.297Z,1370300585.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-06-03T23:03:05.297Z,1370300585.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-06-03T23:03:05.298Z,1370300585.297 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-06-03T23:03:05.298Z,1370300585.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-06-03T23:03:05.298Z,1370300585.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-06-03T23:03:05.299Z,1370300585.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-06-03T23:03:05.299Z,1370300585.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-06-03T23:03:06.585Z,1370300586.585 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-06-03T23:03:06.617Z,1370300586.617 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-06-03T23:03:06.639Z,1370300586.639 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-06-03T23:03:06.682Z,1370300586.682 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

        <ReadDatum>
            <Timeout Duration="P1M" /><Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" /><Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2013-06-03T23:03:06.683Z,1370300586.683 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-06-03T23:03:06.743Z,1370300586.743 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-06-03T23:03:06.745Z,1370300586.745 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-06-03T23:03:06.748Z,1370300586.748 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-06-03T23:03:06.788Z,1370300586.788 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-06-03T23:03:06.803Z,1370300586.803 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-06-03T23:03:06.818Z,1370300586.818 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-06-03T23:03:06.827Z,1370300586.827 [Default:D.SetSpeed](DEBUG): Construct.
2013-06-03T23:03:06.842Z,1370300586.842 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-06-03T23:03:06.847Z,1370300586.847 [Default:F.Wait](DEBUG): Construct Wait.
2013-06-03T23:03:06.851Z,1370300586.851 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="NeedGPS"><True/></DefineArg>

    <Aggregate Id="GPS" >

        <When><Arg Name="NeedGPS"/>
        </When>

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

        <ReadDatum Id="Read_GPS"><Universal:latitude_fix/>
        </ReadDatum>

        <Assign><Arg Name="NeedGPS"/><False/></Assign>

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute RunIn="Sequence">
                    <Setting>
                    <Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped drop weight due to communications timeout</Syslog>
            </Timeout><Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

    <Aggregate Id="CallIridium" >

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or>
            <Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

        <Assign><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

<!-- Wait forever at the surface-->

<!-- Run at low speed -->

    <Guidance:SetSpeed RunIn="Parallel">
        <Setting><Guidance:SetSpeed.period/><Units:second/><Value>5</Value></Setting>
    </Guidance:SetSpeed>

    <Guidance:GoToSurface RunIn="Parallel"/>

    <Guidance:Wait RunIn="Sequence"></Guidance:Wait>

</Mission>


2013-06-03T23:03:06.890Z,1370300586.890 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,DAT,SCPI,Depth_Keller,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-06-03T23:03:06.935Z,1370300586.935 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-06-03T23:03:07.194Z,1370300587.194 [Radio_Freewave](INFO): Powering up
2013-06-03T23:03:07.211Z,1370300587.211 [DAT](INFO): Powering up
2013-06-03T23:03:07.211Z,1370300587.211 [DAT](DEBUG): Initializing DAT.
2013-06-03T23:03:07.299Z,1370300587.299 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-06-03T23:03:07.299Z,1370300587.299 [DVL_micro](INFO): Querying output modes
2013-06-03T23:03:07.299Z,1370300587.299 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-06-03T23:03:07.310Z,1370300587.310 [DVL_micro](DEBUG): cmdResponse: 01
2013-06-03T23:03:07.310Z,1370300587.310 [DVL_micro](INFO): NQ1 output enabled
2013-06-03T23:03:07.311Z,1370300587.311 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-06-03T23:03:07.322Z,1370300587.322 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-06-03T23:03:07.327Z,1370300587.327 [DVL_micro](INFO): pause:Powering down
2013-06-03T23:03:07.455Z,1370300587.455 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-06-03T23:03:07.478Z,1370300587.478 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-06-03T23:03:07.485Z,1370300587.485 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-06-03T23:03:07.491Z,1370300587.490 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-06-03T23:03:07.505Z,1370300587.505 [MassServo](DEBUG): Initializing EZServoServo.
2013-06-03T23:03:07.511Z,1370300587.510 [MassServo](DEBUG): Initializing MassServo.
2013-06-03T23:03:07.535Z,1370300587.535 [RudderServo](DEBUG): Initializing EZServoServo.
2013-06-03T23:03:07.542Z,1370300587.542 [RudderServo](DEBUG): Initializing RudderServo.
2013-06-03T23:03:07.548Z,1370300587.548 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-06-03T23:03:07.554Z,1370300587.554 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-06-03T23:03:10.005Z,1370300590.005 [NAL9602](INFO): Powering up NAL9602
2013-06-03T23:03:20.590Z,1370300600.590 [NAL9602](INFO): NAL9602 initialized
2013-06-03T23:03:20.679Z,1370300600.680 [SBIT](IMPORTANT): Beginning Startup BIT
2013-06-03T23:03:20.689Z,1370300600.689 [CBIT](IMPORTANT): Beginning GF scan
2013-06-03T23:03:27.241Z,1370300607.241 [DAT](INFO): Powering down
2013-06-03T23:03:33.749Z,1370300613.749 [SBIT](FAULT): Mass: EXPECTED:0.005600 ACTUAL:0.002901
2013-06-03T23:03:47.035Z,1370300627.035 [CBIT](IMPORTANT): No ground fault detected
2013-06-03T23:04:02.275Z,1370300642.275 [CommandLine](IMPORTANT): got command failComponent
2013-06-03T23:04:02.275Z,1370300642.275 [CommandLine](IMPORTANT): Failed components:
2013-06-03T23:04:02.275Z,1370300642.275 [CommandLine](IMPORTANT): No failed Components.
2013-06-03T23:04:08.354Z,1370300648.354 [CommandLine](INFO): End of History
2013-06-03T23:04:09.022Z,1370300649.022 [CommandLine](INFO): End of History
2013-06-03T23:04:14.008Z,1370300654.008 [SBIT](CRITICAL): SBIT FAILED
2013-06-03T23:04:14.376Z,1370300654.376 [MissionManager](IMPORTANT): Started mission Startup
2013-06-03T23:04:14.377Z,1370300654.376 [Startup] Running Loop=1
2013-06-03T23:04:14.377Z,1370300654.377 [Startup](INFO): Aggregate::initialize Startup
2013-06-03T23:04:14.377Z,1370300654.377 [Startup:A.GoToSurface] Running Loop=1
2013-06-03T23:04:14.377Z,1370300654.377 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-03T23:04:14.383Z,1370300654.383 [Startup:StartupSatComms] Running Loop=1
2013-06-03T23:04:14.383Z,1370300654.383 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-06-03T23:04:14.383Z,1370300654.383 [Startup:StartupSatComms:A] Running Loop=1
2013-06-03T23:04:14.777Z,1370300654.777 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-06-03T23:04:33.206Z,1370300673.206 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#6 STATUS: 65535
2013-06-03T23:05:14.385Z,1370300714.385 [Startup:StartupSatComms:A](INFO): Timed out from 2013-06-03T23:04:14.4Z
2013-06-03T23:05:14.385Z,1370300714.385 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1
2013-06-03T23:05:14.386Z,1370300714.386 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout
2013-06-03T23:05:14.386Z,1370300714.386 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout
2013-06-03T23:05:14.386Z,1370300714.386 [Startup:StartupSatComms:A] Stopped
2013-06-03T23:05:14.386Z,1370300714.386 [Startup:StartupSatComms:B] Running Loop=1
2013-06-03T23:05:14.805Z,1370300714.805 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-06-03T23:05:18.344Z,1370300718.344 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:22.694Z,1370300722.694 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:27.415Z,1370300727.415 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:31.770Z,1370300731.770 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:36.456Z,1370300736.456 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:40.772Z,1370300740.772 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:45.175Z,1370300745.175 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:49.548Z,1370300749.548 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:53.404Z,1370300753.404 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:05:57.753Z,1370300757.753 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:02.472Z,1370300762.472 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:06.780Z,1370300766.780 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:10.003Z,1370300770.003 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2013-06-03T23:06:11.127Z,1370300771.127 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:15.257Z,1370300775.257 [Startup:StartupSatComms:B](INFO): Timed out from 2013-06-03T23:05:14.4Z
2013-06-03T23:06:15.257Z,1370300775.257 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1
2013-06-03T23:06:15.257Z,1370300775.257 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout
2013-06-03T23:06:15.257Z,1370300775.257 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout
2013-06-03T23:06:15.258Z,1370300775.258 [Startup:StartupSatComms:B] Stopped
2013-06-03T23:06:15.274Z,1370300775.274 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-06-03T23:06:15.274Z,1370300775.274 [Startup:StartupSatComms] Stopped
2013-06-03T23:06:15.274Z,1370300775.274 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-06-03T23:06:15.275Z,1370300775.275 [Startup](INFO): Completed Startup
2013-06-03T23:06:15.275Z,1370300775.275 [Startup] Stopped
2013-06-03T23:06:15.275Z,1370300775.276 [Startup](INFO): Aggregate::uninitialize Startup
2013-06-03T23:06:15.276Z,1370300775.276 [Startup:A.GoToSurface] Stopped
2013-06-03T23:06:15.276Z,1370300775.276 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-03T23:06:15.544Z,1370300775.544 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:15.558Z,1370300775.557 [MissionManager](IMPORTANT): Started mission Default
2013-06-03T23:06:15.558Z,1370300775.558 [Default] Running Loop=1
2013-06-03T23:06:15.558Z,1370300775.558 [Default](INFO): Aggregate::initialize Default
2013-06-03T23:06:15.558Z,1370300775.558 [Default:D.SetSpeed] Running Loop=1
2013-06-03T23:06:15.558Z,1370300775.558 [Default:D.SetSpeed](DEBUG): Initialize.
2013-06-03T23:06:15.558Z,1370300775.558 [Default:E.GoToSurface] Running Loop=1
2013-06-03T23:06:15.558Z,1370300775.558 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-03T23:06:15.559Z,1370300775.559 [Default:Iridium] Running Loop=1
2013-06-03T23:06:15.559Z,1370300775.559 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-06-03T23:06:15.559Z,1370300775.559 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-06-03T23:06:15.559Z,1370300775.559 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-06-03T23:06:15.559Z,1370300775.559 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-06-03T23:06:15.559Z,1370300775.559 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-03T23:06:15.560Z,1370300775.560 [Default:E.GoToSurface] Running Loop=1
2013-06-03T23:06:15.565Z,1370300775.565 [Default:D.SetSpeed] Running Loop=1
2013-06-03T23:06:15.571Z,1370300775.571 [Default:CallIridium] Running Loop=1
2013-06-03T23:06:15.571Z,1370300775.571 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-06-03T23:06:15.571Z,1370300775.571 [Default:CallIridium:A] Running Loop=1
2013-06-03T23:06:15.573Z,1370300775.573 [Default:CallIridium:A] Stopped
2013-06-03T23:06:15.573Z,1370300775.573 [Default:CallIridium:B] Running Loop=1
2013-06-03T23:06:15.573Z,1370300775.573 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-06-03T23:06:15.579Z,1370300775.579 [Default:Iridium:B.GoToSurface] Stopped
2013-06-03T23:06:15.579Z,1370300775.579 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-03T23:06:15.580Z,1370300775.580 [Default:Iridium:Read_Iridium] Running Loop=1
2013-06-03T23:06:15.580Z,1370300775.580 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-06-03T23:06:15.585Z,1370300775.585 [Default:GPS] Running Loop=1
2013-06-03T23:06:15.585Z,1370300775.585 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-06-03T23:06:15.585Z,1370300775.585 [Default:GPS:A.SetSpeed] Running Loop=1
2013-06-03T23:06:15.585Z,1370300775.585 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-06-03T23:06:15.586Z,1370300775.586 [Default:GPS:B.GoToSurface] Running Loop=1
2013-06-03T23:06:15.586Z,1370300775.586 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-03T23:06:15.592Z,1370300775.592 [Default:GPS:B.GoToSurface] Stopped
2013-06-03T23:06:15.592Z,1370300775.592 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-03T23:06:15.592Z,1370300775.592 [Default:GPS:Read_GPS] Running Loop=1
2013-06-03T23:06:15.592Z,1370300775.592 [Default:GPS:A.SetSpeed] Running Loop=1
2013-06-03T23:06:15.918Z,1370300775.918 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-06-03T23:06:15.921Z,1370300775.921 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-06-03T23:06:19.396Z,1370300779.396 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:23.749Z,1370300783.749 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:28.044Z,1370300788.044 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:31.491Z,1370300791.491 [CommandLine](IMPORTANT): got command report touch homing_target_range
2013-06-03T23:06:31.492Z,1370300791.492 [CommandLine](IMPORTANT): got command report touch homing_target_azimuth
2013-06-03T23:06:31.493Z,1370300791.493 [CommandLine](IMPORTANT): got command report touch homing_target_heading
2013-06-03T23:06:31.495Z,1370300791.495 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2013-06-03T23:06:31.496Z,1370300791.496 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2013-06-03T23:06:31.497Z,1370300791.497 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.809998 degree
2013-06-03T23:06:31.499Z,1370300791.499 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2013-06-03T23:06:32.208Z,1370300792.208 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,DAT,SCPI,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-06-03T23:06:32.397Z,1370300792.397 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:32.417Z,1370300792.417 [Default:GPS:Read_GPS] Stopped
2013-06-03T23:06:32.417Z,1370300792.418 [Default:GPS:D] Running Loop=1
2013-06-03T23:06:32.854Z,1370300792.854 [Default:GPS:D] Stopped
2013-06-03T23:06:32.854Z,1370300792.854 [Default:GPS](INFO): Completed Default:GPS
2013-06-03T23:06:32.854Z,1370300792.855 [Default:GPS] Stopped
2013-06-03T23:06:32.855Z,1370300792.855 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-06-03T23:06:32.855Z,1370300792.855 [Default:GPS:A.SetSpeed] Stopped
2013-06-03T23:06:32.855Z,1370300792.855 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-06-03T23:06:34.733Z,1370300794.733 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:39.105Z,1370300799.105 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:41.617Z,1370300801.617 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:46.048Z,1370300806.048 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:48.206Z,1370300808.207 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:51.038Z,1370300811.037 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#6 STATUS: 65535
2013-06-03T23:06:52.564Z,1370300812.564 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:54.907Z,1370300814.907 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:06:59.260Z,1370300819.260 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:01.624Z,1370300821.624 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:05.908Z,1370300825.908 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:08.183Z,1370300828.182 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:12.644Z,1370300832.644 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:14.948Z,1370300834.948 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:19.364Z,1370300839.364 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:21.821Z,1370300841.821 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:26.100Z,1370300846.100 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:28.444Z,1370300848.444 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:32.748Z,1370300852.748 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:35.152Z,1370300855.152 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:39.404Z,1370300859.404 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:41.764Z,1370300861.764 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:46.136Z,1370300866.136 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:48.537Z,1370300868.537 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:52.976Z,1370300872.976 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:55.347Z,1370300875.346 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:07:59.836Z,1370300879.836 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:02.156Z,1370300882.156 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:06.535Z,1370300886.535 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:08.919Z,1370300888.918 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:13.404Z,1370300893.404 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:15.758Z,1370300895.759 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:20.176Z,1370300900.176 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:22.512Z,1370300902.512 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:26.964Z,1370300906.964 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:29.216Z,1370300909.216 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:33.620Z,1370300913.620 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:35.944Z,1370300915.944 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:40.212Z,1370300920.212 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:42.568Z,1370300922.568 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:46.972Z,1370300926.972 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:49.444Z,1370300929.444 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:53.836Z,1370300933.836 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:56.183Z,1370300936.183 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:08:59.183Z,1370300939.183 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2013-06-03T23:09:02.779Z,1370300942.779 [Default:Iridium:Read_Iridium] Stopped
2013-06-03T23:09:02.780Z,1370300942.780 [Default:Iridium](INFO): Completed Default:Iridium
2013-06-03T23:09:02.780Z,1370300942.780 [Default:Iridium] Stopped
2013-06-03T23:09:02.780Z,1370300942.780 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-06-03T23:09:02.780Z,1370300942.780 [Default:Iridium:A.SetSpeed] Stopped
2013-06-03T23:09:02.780Z,1370300942.780 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-06-03T23:09:02.780Z,1370300942.780 [Default:F.Wait] Running Loop=1
2013-06-03T23:09:02.780Z,1370300942.780 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-06-03T23:09:03.629Z,1370300943.629 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-06-03T23:09:03.629Z,1370300943.629 [Default:CallIridium:B] Stopped
2013-06-03T23:09:03.630Z,1370300943.630 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-06-03T23:09:03.630Z,1370300943.630 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-06-03T23:09:03.630Z,1370300943.630 [Default:CallIridium] Stopped
2013-06-03T23:09:03.630Z,1370300943.630 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-06-03T23:09:08.296Z,1370300948.296 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:09:18.378Z,1370300958.378 [CommandLine](IMPORTANT): got command conversation stop
2013-06-03T23:09:18.379Z,1370300958.379 [CommandLine](IMPORTANT): Conversation is stopped.
2013-06-03T23:09:20.002Z,1370300960.002 [CommandLine](IMPORTANT): got command conversation stop
2013-06-03T23:09:20.003Z,1370300960.003 [CommandLine](IMPORTANT): Conversation is stopped.
2013-06-03T23:09:23.264Z,1370300963.263 [NAL9602](INFO): Powering down
2013-06-03T23:09:23.873Z,1370300963.874 [CommandLine](IMPORTANT): got command report touch homing_target_range
2013-06-03T23:09:24.183Z,1370300964.183 [CommandLine](IMPORTANT): got command report touch homing_target_azimuth
2013-06-03T23:09:24.491Z,1370300964.491 [CommandLine](IMPORTANT): got command report touch homing_target_heading
2013-06-03T23:09:24.804Z,1370300964.804 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2013-06-03T23:09:39.152Z,1370300979.151 [CommandLine](IMPORTANT): got command report touch homing_target_range
2013-06-03T23:09:39.493Z,1370300979.493 [CommandLine](IMPORTANT): got command report touch homing_target_azimuth
2013-06-03T23:09:39.898Z,1370300979.898 [CommandLine](IMPORTANT): got command report touch homing_target_heading
2013-06-03T23:09:40.205Z,1370300980.205 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2013-06-03T23:09:51.235Z,1370300991.235 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2013-06-03T23:10:13.186Z,1370301013.186 [CommandLine](IMPORTANT): got command run ./Missions/Engineering/Homing_pursuit.xml
2013-06-03T23:10:13.187Z,1370301013.187 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/Homing_pursuit.xml
2013-06-03T23:10:13.346Z,1370301013.346 [MissionManager](INFO): DefineArg homing_pursuit.Speed = 1.000000 m/s
2013-06-03T23:10:13.354Z,1370301013.353 [MissionManager](INFO): DefineArg homing_pursuit.MassDefault = -0.006000 n/a
2013-06-03T23:10:13.358Z,1370301013.358 [MissionManager](INFO): DefineArg homing_pursuit.BuoyancyNeutral = 0.000560 n/a
2013-06-03T23:10:13.361Z,1370301013.361 [MissionManager](INFO): DefineArg homing_pursuit.MinRange = 0.200000 m
2013-06-03T23:10:13.365Z,1370301013.365 [MissionManager](INFO): DefineArg homing_pursuit.OnewayRange = 40.000000 m
2013-06-03T23:10:13.368Z,1370301013.368 [MissionManager](INFO): DefineArg homing_pursuit.TimeoutDuration = 40.000000 min
2013-06-03T23:10:13.371Z,1370301013.371 [MissionManager](INFO): DefineArg homing_pursuit.PowerOnDATTimeoutDuration = 1.000000 min
2013-06-03T23:10:13.375Z,1370301013.375 [MissionManager](INFO): DefineArg homing_pursuit.RolloutTimeout = 7.000000 min
2013-06-03T23:10:13.377Z,1370301013.378 [MissionManager](INFO): DefineArg homing_pursuit.HomingDepth = 7.000000 m
2013-06-03T23:10:13.381Z,1370301013.381 [MissionManager](INFO): DefineArg homing_pursuit.MaxDepth = 45.000000 m
2013-06-03T23:10:13.385Z,1370301013.385 [MissionManager](INFO): DefineArg homing_pursuit.EnabledNeilBrown = 1.000000 n/a
2013-06-03T23:10:13.403Z,1370301013.402 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2013-06-03T23:10:13.439Z,1370301013.439 [MissionManager](INFO): DefineArg homing_pursuit:SurfaceComms.SurfaceDepthRate = nan m/s
2013-06-03T23:10:13.443Z,1370301013.443 [MissionManager](INFO): DefineArg homing_pursuit:SurfaceComms.SurfacePitch = nan arcdeg
2013-06-03T23:10:13.446Z,1370301013.446 [MissionManager](INFO): DefineArg homing_pursuit:SurfaceComms.SurfaceSpeed = 0.500000 m/s
2013-06-03T23:10:13.449Z,1370301013.449 [MissionManager](INFO): DefineArg homing_pursuit:SurfaceComms.IridiumTimeout = 30.000000 min
2013-06-03T23:10:13.452Z,1370301013.452 [MissionManager](INFO): DefineArg homing_pursuit:SurfaceComms.UnpackSplitAtSurface = 0 bool
2013-06-03T23:10:13.453Z,1370301013.453 [homing_pursuit:SurfaceComms:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-06-03T23:10:13.467Z,1370301013.467 [homing_pursuit:B.Pitch](DEBUG): Construct.
2013-06-03T23:10:13.473Z,1370301013.473 [homing_pursuit:C.Buoyancy](DEBUG): Construct Buoyancy.
2013-06-03T23:10:13.481Z,1370301013.481 [homing_pursuit:PowerOnDAT:A.SetSpeed](DEBUG): Construct.
2013-06-03T23:10:13.484Z,1370301013.484 [homing_pursuit:PowerOnDAT:B.Pitch](DEBUG): Construct.
2013-06-03T23:10:13.506Z,1370301013.506 [homing_pursuit:PowerOnDAT:C.Wait](DEBUG): Construct Wait.
2013-06-03T23:10:13.514Z,1370301013.514 [homing_pursuit:InitialPursuit:A.SetSpeed](DEBUG): Construct.
2013-06-03T23:10:13.517Z,1370301013.517 [homing_pursuit:InitialPursuit:B.Pitch](DEBUG): Construct.
2013-06-03T23:10:13.544Z,1370301013.543 [homing_pursuit:InitialPursuit:C.Point](DEBUG): Construct.
2013-06-03T23:10:13.560Z,1370301013.560 [homing_pursuit:InitialPursuit:D.Wait](DEBUG): Construct Wait.
2013-06-03T23:10:13.569Z,1370301013.569 [homing_pursuit:ClosePursuit:B.SetSpeed](DEBUG): Construct.
2013-06-03T23:10:13.597Z,1370301013.597 [homing_pursuit:ClosePursuit:C.Pitch](DEBUG): Construct.
2013-06-03T23:10:13.616Z,1370301013.616 [homing_pursuit:ClosePursuit:D.Point](DEBUG): Construct.
2013-06-03T23:10:13.628Z,1370301013.628 [homing_pursuit:ClosePursuit:E.Wait](DEBUG): Construct Wait.
2013-06-03T23:10:13.639Z,1370301013.639 [homing_pursuit:Rollout:A.SetSpeed](DEBUG): Construct.
2013-06-03T23:10:13.642Z,1370301013.642 [homing_pursuit:Rollout:B.Pitch](DEBUG): Construct.
2013-06-03T23:10:13.648Z,1370301013.648 [homing_pursuit:Rollout:C.Wait](DEBUG): Construct Wait.
2013-06-03T23:10:13.660Z,1370301013.660 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Sensor="Tethys/Sensor"
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://aosn.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="homing_pursuit">

<!-- Define mission arguments -->

    <DefineArg Name="Speed"><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="MassDefault"><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="MinRange"><Description>
        Minimum range to continue to update heading being driven.
    </Description><Units:meter/><Value>0.2</Value></DefineArg>

    <DefineArg Name="OnewayRange"><Description>
        Minimum range to continue to update heading being driven. Max is 120
        meters due to maximum sample output from modem in oneway mode.
    </Description><Units:meter/><Value>40.0</Value></DefineArg>

    <DefineArg Name="TimeoutDuration"><Description>
        Maximum length of mission
    </Description><Units:minute/><Value>40</Value></DefineArg>

    <DefineArg Name="PowerOnDATTimeoutDuration"><Description>
        Wait this long after turning on DAT before starting any behaviors that require DAT output.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="RolloutTimeout"><Description>
        Drive on your last heading for this long after approaching within minimum range.
    </Description><Units:minute/><Value>7</Value></DefineArg>

    <DefineArg Name="HomingDepth"><Description>
        Depth for initial approach.
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>45</Value></DefineArg>

    <DefineArg Name="EnabledNeilBrown"><Description>
        Set either this or PeakDetectChlActive to True in order to sample from
        the Neil Brown CTD.
    </Description><CustomUri Uri="Config/Science.CTD_NeilBrown_enable"/></DefineArg>

<!-- TODO any other science sensors that should be active? -->

<!-- Begin the mission. -->

    <Timeout Duration="TimeoutDuration"/>

<!-- Safety nets and surfacing behaviors -->
<!-- TODO re-enable
    <Guidance:AltitudeEnvelope RunIn="Parallel">
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Units:meter/><Value>7</Value></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:DepthEnvelope RunIn="Parallel">
        <Setting><Guidance:DepthEnvelope.minDepth/><Units:meter/><Value>0</Value></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:OffshoreEnvelope RunIn="Parallel">
        <Setting><Guidance:OffshoreEnvelope.minOffshore/><Units:kilometer/><Value>2.0</Value></Setting>
    </Guidance:OffshoreEnvelope>

-->

    <Insert Filename="Insert/Surface.xml" Id="SurfaceComms" />

<!-- Set default mass and pump positions -->

    <Guidance:Pitch RunIn="Parallel">
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy RunIn="Parallel">
        <Setting><Guidance:Buoyancy.position /><Arg Name="BuoyancyNeutral"/></Setting>
    </Guidance:Buoyancy>

<!-- Request desired engineering data -->

    <ReadData Strategy="MinError">
        <Universal:height_above_sea_floor/>
    </ReadData>

    <ReadData Strategy="AllDevices">
        <Universal:platform_orientation/>
    </ReadData>

    <ReadData>
        <Universal:homing_target_range/>
    </ReadData>

<!-- Start actual engineering mission sequence. -->

    <Aggregate Id="PowerOnDAT">
	
        <Description>
            Give the DAT some time to start up before you ask for ranges from it.
        </Description>

        <Timeout Duration="PowerOnDATTimeoutDuration"/>

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:Pitch RunIn="Parallel">
            <Setting><Guidance:Pitch.depth/><Arg Name="HomingDepth"/></Setting>
        </Guidance:Pitch>

        <Guidance:Wait RunIn="Sequence">
            <Setting><Guidance:Wait.duration/><Units:second/><Value>30</Value></Setting>
        </Guidance:Wait>

    </Aggregate>

    <Aggregate Id="InitialPursuit">

	<Description>
            Move toward the target using normal two-way queries. (These will also provide the heading of the compass attached to the acoustic modem.)

            Update the commanded heading with *Universal:homing_target_azimuth* each cycle. Continue until you approach within *OnewayRange*, then move on to the next aggregate.
        </Description>

        <Until><Universal:homing_target_range/>
            <Lt><Arg Name="OnewayRange"/></Lt>
        </Until>

        <!-- <Timeout Duration="InitialPursuitTimeout"/> -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:Pitch RunIn="Parallel">
            <Setting><Guidance:Pitch.depth/><Arg Name="HomingDepth"/></Setting>
        </Guidance:Pitch>

        <Guidance:Point RunIn="Parallel">
            <Setting><Guidance:Point.heading/><Universal:homing_target_azimuth/></Setting>
        </Guidance:Point>

        <Guidance:Wait RunIn="Sequence"/>

    </Aggregate>

    <Aggregate Id="ClosePursuit">

	<Description>
            Move toward the target using fast one-way queries. (These will *NOT* provide the heading of the compass attached to the acoustic modem.)

            Update the commanded heading with *Universal:homing_target_azimuth* each cycle. Continue until you approach within *MinRange*, then move on to the next aggregate.
        </Description>

        <Until><Universal:homing_target_range/>
            <Lt><Arg Name="MinRange"/></Lt>
        </Until>

        <!-- <Timeout Duration="ClosePursuitTimeout"/> -->

        <Assign RunIn="Parallel"><Sensor:DAT.onewayRequestedCmd/><Units:bool/><Value>1</Value></Assign>

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:Pitch RunIn="Parallel">
            <Setting><Guidance:Pitch.depth/><Arg Name="HomingDepth"/></Setting>
        </Guidance:Pitch>

        <Guidance:Point RunIn="Sequence">
            <Setting><Guidance:Point.heading/><Universal:homing_target_azimuth/></Setting>
        </Guidance:Point>

        <Guidance:Wait RunIn="Sequence"/>

    </Aggregate>

    <Aggregate Id="Rollout">

	<Description>
            Move away from the target at the last commanded heading until *RolloutTimeout* has passed.

            (DAT should still be reporting. Target acoustic modem may still be in Oneway mode.)
        </Description>

        <Timeout Duration="RolloutTimeout"/>

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:Pitch RunIn="Parallel">
            <Setting><Guidance:Pitch.depth/><Arg Name="HomingDepth"/></Setting>
        </Guidance:Pitch>

        <Guidance:Wait RunIn="Sequence"/>

    </Aggregate>

    <Call Id="PhoneHome" RefId="SurfaceComms"/>

</Mission>


2013-06-03T23:10:13.660Z,1370301013.660 [CommandLine](IMPORTANT): Running ./Missions/Engineering/Homing_pursuit.xml
2013-06-03T23:10:13.942Z,1370301013.942 [Default] Stopped
2013-06-03T23:10:13.942Z,1370301013.942 [Default](INFO): Aggregate::uninitialize Default
2013-06-03T23:10:13.942Z,1370301013.942 [Default:D.SetSpeed] Stopped
2013-06-03T23:10:13.942Z,1370301013.943 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-06-03T23:10:13.943Z,1370301013.943 [Default:E.GoToSurface] Stopped
2013-06-03T23:10:13.943Z,1370301013.943 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-03T23:10:13.943Z,1370301013.943 [Default:F.Wait] Stopped
2013-06-03T23:10:13.943Z,1370301013.943 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2013-06-03T23:10:13.943Z,1370301013.943 [MissionManager](IMPORTANT): Started mission homing_pursuit
2013-06-03T23:10:13.943Z,1370301013.943 [homing_pursuit] Running Loop=1
2013-06-03T23:10:13.943Z,1370301013.943 [homing_pursuit](INFO): Aggregate::initialize homing_pursuit
2013-06-03T23:10:13.943Z,1370301013.943 [homing_pursuit:B.Pitch] Running Loop=1
2013-06-03T23:10:13.943Z,1370301013.944 [homing_pursuit:B.Pitch](DEBUG): Initialize.
2013-06-03T23:10:13.944Z,1370301013.944 [homing_pursuit:C.Buoyancy] Running Loop=1
2013-06-03T23:10:13.944Z,1370301013.944 [homing_pursuit:C.Buoyancy](DEBUG): Initialize Buoyancy Component.
2013-06-03T23:10:13.944Z,1370301013.944 [homing_pursuit:D] Running Loop=1
2013-06-03T23:10:13.944Z,1370301013.944 [homing_pursuit:E] Running Loop=1
2013-06-03T23:10:13.944Z,1370301013.944 [homing_pursuit:F] Running Loop=1
2013-06-03T23:10:13.944Z,1370301013.944 [homing_pursuit:SurfaceComms] Running Loop=1
2013-06-03T23:10:13.945Z,1370301013.944 [homing_pursuit:SurfaceComms](INFO): Aggregate::initialize homing_pursuit:SurfaceComms
2013-06-03T23:10:13.945Z,1370301013.945 [homing_pursuit:SurfaceComms:A.GoToSurface] Running Loop=1
2013-06-03T23:10:13.945Z,1370301013.945 [homing_pursuit:SurfaceComms:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-06-03T23:10:13.945Z,1370301013.945 [homing_pursuit:F] Running Loop=1
2013-06-03T23:10:13.946Z,1370301013.946 [homing_pursuit:F](DEBUG): Initialize ReadDataComponent to sense homing_target_range
2013-06-03T23:10:13.947Z,1370301013.947 [homing_pursuit:E] Running Loop=1
2013-06-03T23:10:13.948Z,1370301013.948 [homing_pursuit:E](DEBUG): Initialize ReadDataComponent to sense platform_orientation
2013-06-03T23:10:13.950Z,1370301013.950 [homing_pursuit:D] Running Loop=1
2013-06-03T23:10:13.951Z,1370301013.951 [homing_pursuit:D](DEBUG): Initialize ReadDataComponent to sense height_above_sea_floor
2013-06-03T23:10:13.952Z,1370301013.952 [homing_pursuit:C.Buoyancy] Running Loop=1
2013-06-03T23:10:13.958Z,1370301013.958 [homing_pursuit:B.Pitch] Running Loop=1
2013-06-03T23:10:13.969Z,1370301013.968 [homing_pursuit:SurfaceComms:B] Running Loop=1
2013-06-03T23:10:13.969Z,1370301013.969 [homing_pursuit:SurfaceComms:B](INFO): Aggregate::initialize homing_pursuit:SurfaceComms:B
2013-06-03T23:10:13.969Z,1370301013.969 [homing_pursuit:SurfaceComms:B:A] Running Loop=1
2013-06-03T23:10:14.275Z,1370301014.275 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-06-03T23:10:14.275Z,1370301014.275 [DVL_micro](INFO): resume:Powering up
2013-06-03T23:10:14.275Z,1370301014.275 [DVL_micro](INFO): Cycling power to configure device.
2013-06-03T23:10:14.317Z,1370301014.318 [DAT](INFO): Powering up
2013-06-03T23:10:14.329Z,1370301014.329 [homing_pursuit:SurfaceComms:B] Stopped
2013-06-03T23:10:14.329Z,1370301014.329 [homing_pursuit:SurfaceComms:B](INFO): Aggregate::uninitialize homing_pursuit:SurfaceComms:B
2013-06-03T23:10:14.329Z,1370301014.329 [homing_pursuit:SurfaceComms:B:A] Stopped
2013-06-03T23:10:14.329Z,1370301014.329 [homing_pursuit:SurfaceComms:C] Running Loop=1
2013-06-03T23:10:14.329Z,1370301014.329 [homing_pursuit:SurfaceComms:C](INFO): Aggregate::initialize homing_pursuit:SurfaceComms:C
2013-06-03T23:10:14.330Z,1370301014.330 [homing_pursuit:SurfaceComms:C:A] Running Loop=1
2013-06-03T23:10:14.724Z,1370301014.724 [homing_pursuit:F] Preempted
2013-06-03T23:10:14.724Z,1370301014.724 [homing_pursuit:E] Preempted
2013-06-03T23:10:14.724Z,1370301014.724 [homing_pursuit:D] Preempted
2013-06-03T23:10:14.724Z,1370301014.724 [homing_pursuit:C.Buoyancy] Preempted
2013-06-03T23:10:14.724Z,1370301014.724 [homing_pursuit:B.Pitch] Preempted
2013-06-03T23:10:14.725Z,1370301014.725 [homing_pursuit:SurfaceComms:C:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-06-03T23:10:14.727Z,1370301014.727 [homing_pursuit:SurfaceComms:C:A] Stopped
2013-06-03T23:10:14.727Z,1370301014.727 [homing_pursuit:SurfaceComms:C:B] Running Loop=1
2013-06-03T23:10:15.112Z,1370301015.112 [homing_pursuit:SurfaceComms:C:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-06-03T23:10:15.559Z,1370301015.559 [NAL9602](INFO): Powering up
2013-06-03T23:10:16.282Z,1370301016.282 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-06-03T23:10:16.283Z,1370301016.283 [DVL_micro](INFO): Querying output modes
2013-06-03T23:10:16.283Z,1370301016.283 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-06-03T23:10:16.294Z,1370301016.294 [DVL_micro](DEBUG): cmdResponse: 01
2013-06-03T23:10:16.294Z,1370301016.294 [DVL_micro](INFO): NQ1 output enabled
2013-06-03T23:10:16.295Z,1370301016.295 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-06-03T23:10:16.306Z,1370301016.306 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-06-03T23:10:16.309Z,1370301016.309 [DVL_micro](INFO): pause:Powering down
2013-06-03T23:10:25.930Z,1370301025.930 [NAL9602](INFO): NAL9602 initialized
2013-06-03T23:10:27.880Z,1370301027.880 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:32.256Z,1370301032.256 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:34.664Z,1370301034.664 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:35.037Z,1370301035.037 [DAT](INFO): Powering down
2013-06-03T23:10:39.060Z,1370301039.060 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:41.404Z,1370301041.404 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:45.677Z,1370301045.677 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:48.060Z,1370301048.060 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:52.332Z,1370301052.332 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:54.752Z,1370301054.752 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:10:59.144Z,1370301059.144 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:11:01.572Z,1370301061.572 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:11:03.338Z,1370301063.338 [CommandLine](IMPORTANT): got command conversation stop
2013-06-03T23:11:03.339Z,1370301063.339 [CommandLine](IMPORTANT): Conversation is stopped.
2013-06-03T23:11:03.339Z,1370301063.339 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2013-06-03T23:11:06.796Z,1370301066.796 [homing_pursuit:SurfaceComms:C:B] Stopped
2013-06-03T23:11:06.797Z,1370301066.796 [homing_pursuit:SurfaceComms:C:C] Running Loop=1
2013-06-03T23:11:07.702Z,1370301067.702 [homing_pursuit:SurfaceComms:C:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-06-03T23:11:07.704Z,1370301067.704 [homing_pursuit:SurfaceComms:C:C] Stopped
2013-06-03T23:11:07.704Z,1370301067.704 [homing_pursuit:SurfaceComms:C](INFO): Completed homing_pursuit:SurfaceComms:C
2013-06-03T23:11:07.704Z,1370301067.704 [homing_pursuit:SurfaceComms:C] Stopped
2013-06-03T23:11:07.704Z,1370301067.704 [homing_pursuit:SurfaceComms:C](INFO): Aggregate::uninitialize homing_pursuit:SurfaceComms:C
2013-06-03T23:11:07.705Z,1370301067.705 [homing_pursuit:SurfaceComms](INFO): Completed homing_pursuit:SurfaceComms
2013-06-03T23:11:07.705Z,1370301067.705 [homing_pursuit:SurfaceComms] Stopped
2013-06-03T23:11:07.705Z,1370301067.705 [homing_pursuit:SurfaceComms](INFO): Aggregate::uninitialize homing_pursuit:SurfaceComms
2013-06-03T23:11:07.705Z,1370301067.705 [homing_pursuit:SurfaceComms:A.GoToSurface] Stopped
2013-06-03T23:11:07.706Z,1370301067.705 [homing_pursuit:SurfaceComms:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-06-03T23:11:07.706Z,1370301067.706 [homing_pursuit:PowerOnDAT] Running Loop=1
2013-06-03T23:11:07.706Z,1370301067.706 [homing_pursuit:PowerOnDAT](INFO): Aggregate::initialize homing_pursuit:PowerOnDAT
2013-06-03T23:11:07.706Z,1370301067.706 [homing_pursuit:PowerOnDAT:A.SetSpeed] Running Loop=1
2013-06-03T23:11:07.706Z,1370301067.706 [homing_pursuit:PowerOnDAT:A.SetSpeed](DEBUG): Initialize.
2013-06-03T23:11:07.706Z,1370301067.706 [homing_pursuit:PowerOnDAT:B.Pitch] Running Loop=1
2013-06-03T23:11:07.706Z,1370301067.707 [homing_pursuit:PowerOnDAT:B.Pitch](DEBUG): Initialize.
2013-06-03T23:11:07.707Z,1370301067.707 [homing_pursuit:PowerOnDAT:C.Wait] Running Loop=1
2013-06-03T23:11:07.707Z,1370301067.707 [homing_pursuit:PowerOnDAT:C.Wait](DEBUG): Initialize Wait Component.
2013-06-03T23:11:07.940Z,1370301067.940 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-06-03T23:11:07.964Z,1370301067.964 [homing_pursuit:PowerOnDAT:B.Pitch] Running Loop=1
2013-06-03T23:11:07.970Z,1370301067.970 [homing_pursuit:PowerOnDAT:A.SetSpeed] Running Loop=1
2013-06-03T23:11:08.011Z,1370301068.011 [homing_pursuit:F] Running Loop=1
2013-06-03T23:11:08.011Z,1370301068.011 [homing_pursuit:E] Running Loop=1
2013-06-03T23:11:08.011Z,1370301068.011 [homing_pursuit:D] Running Loop=1
2013-06-03T23:11:08.012Z,1370301068.011 [homing_pursuit:C.Buoyancy] Running Loop=1
2013-06-03T23:11:08.012Z,1370301068.012 [homing_pursuit:B.Pitch] Running Loop=1
2013-06-03T23:11:08.063Z,1370301068.063 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-06-03T23:11:08.063Z,1370301068.063 [DVL_micro](INFO): resume:Powering up
2013-06-03T23:11:08.063Z,1370301068.063 [DVL_micro](INFO): Cycling power to configure device.
2013-06-03T23:11:08.314Z,1370301068.314 [DAT](INFO): Powering up
2013-06-03T23:11:10.070Z,1370301070.070 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-06-03T23:11:10.071Z,1370301070.071 [DVL_micro](INFO): Querying output modes
2013-06-03T23:11:10.071Z,1370301070.071 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-06-03T23:11:10.082Z,1370301070.082 [DVL_micro](DEBUG): cmdResponse: 01
2013-06-03T23:11:10.082Z,1370301070.082 [DVL_micro](INFO): NQ1 output enabled
2013-06-03T23:11:10.082Z,1370301070.082 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-06-03T23:11:10.094Z,1370301070.094 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-06-03T23:11:12.614Z,1370301072.614 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:13.499Z,1370301073.499 [NAL9602](INFO): Powering down
2013-06-03T23:11:15.174Z,1370301075.174 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:17.750Z,1370301077.750 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:20.318Z,1370301080.318 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:22.882Z,1370301082.882 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:25.450Z,1370301085.450 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:28.002Z,1370301088.002 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:30.562Z,1370301090.562 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:32.122Z,1370301092.122 [Reporter](INFO): homing_target_range 0.300000 m
2013-06-03T23:11:32.123Z,1370301092.123 [Reporter](INFO): homing_target_azimuth 228.423902 arcdeg
2013-06-03T23:11:32.123Z,1370301092.124 [Reporter](INFO): homing_target_heading 169.300003 arcdeg
2013-06-03T23:11:33.130Z,1370301093.130 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:35.698Z,1370301095.698 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:36.102Z,1370301096.102 [Reporter](INFO): homing_target_range 0.200000 m
2013-06-03T23:11:36.103Z,1370301096.103 [Reporter](INFO): homing_target_azimuth 282.258606 arcdeg
2013-06-03T23:11:36.103Z,1370301096.103 [Reporter](INFO): homing_target_heading 169.000000 arcdeg
2013-06-03T23:11:38.012Z,1370301098.012 [homing_pursuit:PowerOnDAT:C.Wait](INFO): Done Waiting.
2013-06-03T23:11:38.012Z,1370301098.012 [homing_pursuit:PowerOnDAT:C.Wait] Stopped
2013-06-03T23:11:38.012Z,1370301098.012 [homing_pursuit:PowerOnDAT:C.Wait](DEBUG): Uninitialize Wait Component.
2013-06-03T23:11:38.013Z,1370301098.013 [homing_pursuit:PowerOnDAT](INFO): Completed homing_pursuit:PowerOnDAT
2013-06-03T23:11:38.013Z,1370301098.013 [homing_pursuit:PowerOnDAT] Stopped
2013-06-03T23:11:38.013Z,1370301098.013 [homing_pursuit:PowerOnDAT](INFO): Aggregate::uninitialize homing_pursuit:PowerOnDAT
2013-06-03T23:11:38.013Z,1370301098.013 [homing_pursuit:PowerOnDAT:A.SetSpeed] Stopped
2013-06-03T23:11:38.013Z,1370301098.013 [homing_pursuit:PowerOnDAT:A.SetSpeed](DEBUG): Uninitialize.
2013-06-03T23:11:38.014Z,1370301098.013 [homing_pursuit:PowerOnDAT:B.Pitch] Stopped
2013-06-03T23:11:38.014Z,1370301098.014 [homing_pursuit:InitialPursuit] Running Loop=1
2013-06-03T23:11:38.014Z,1370301098.014 [homing_pursuit:InitialPursuit](INFO): Aggregate::initialize homing_pursuit:InitialPursuit
2013-06-03T23:11:38.014Z,1370301098.014 [homing_pursuit:InitialPursuit:A.SetSpeed] Running Loop=1
2013-06-03T23:11:38.014Z,1370301098.014 [homing_pursuit:InitialPursuit:A.SetSpeed](DEBUG): Initialize.
2013-06-03T23:11:38.014Z,1370301098.014 [homing_pursuit:InitialPursuit:B.Pitch] Running Loop=1
2013-06-03T23:11:38.014Z,1370301098.014 [homing_pursuit:InitialPursuit:B.Pitch](DEBUG): Initialize.
2013-06-03T23:11:38.015Z,1370301098.015 [homing_pursuit:InitialPursuit:C.Point] Running Loop=1
2013-06-03T23:11:38.015Z,1370301098.015 [homing_pursuit:InitialPursuit:C.Point](DEBUG): Initialize.
2013-06-03T23:11:38.015Z,1370301098.015 [homing_pursuit:InitialPursuit:D.Wait] Running Loop=1
2013-06-03T23:11:38.015Z,1370301098.015 [homing_pursuit:InitialPursuit:D.Wait](DEBUG): Initialize Wait Component.
2013-06-03T23:11:38.262Z,1370301098.262 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:38.429Z,1370301098.429 [homing_pursuit:InitialPursuit] Stopped
2013-06-03T23:11:38.429Z,1370301098.429 [homing_pursuit:InitialPursuit](INFO): Aggregate::uninitialize homing_pursuit:InitialPursuit
2013-06-03T23:11:38.430Z,1370301098.430 [homing_pursuit:InitialPursuit:A.SetSpeed] Stopped
2013-06-03T23:11:38.430Z,1370301098.430 [homing_pursuit:InitialPursuit:A.SetSpeed](DEBUG): Uninitialize.
2013-06-03T23:11:38.430Z,1370301098.430 [homing_pursuit:InitialPursuit:B.Pitch] Stopped
2013-06-03T23:11:38.430Z,1370301098.430 [homing_pursuit:InitialPursuit:C.Point] Stopped
2013-06-03T23:11:38.430Z,1370301098.430 [homing_pursuit:InitialPursuit:D.Wait] Stopped
2013-06-03T23:11:38.430Z,1370301098.430 [homing_pursuit:InitialPursuit:D.Wait](DEBUG): Uninitialize Wait Component.
2013-06-03T23:11:38.430Z,1370301098.430 [homing_pursuit:ClosePursuit] Running Loop=1
2013-06-03T23:11:38.431Z,1370301098.431 [homing_pursuit:ClosePursuit](INFO): Aggregate::initialize homing_pursuit:ClosePursuit
2013-06-03T23:11:38.431Z,1370301098.431 [homing_pursuit:ClosePursuit:A] Running Loop=1
2013-06-03T23:11:38.431Z,1370301098.431 [homing_pursuit:ClosePursuit:B.SetSpeed] Running Loop=1
2013-06-03T23:11:38.431Z,1370301098.431 [homing_pursuit:ClosePursuit:B.SetSpeed](DEBUG): Initialize.
2013-06-03T23:11:38.431Z,1370301098.431 [homing_pursuit:ClosePursuit:C.Pitch] Running Loop=1
2013-06-03T23:11:38.431Z,1370301098.431 [homing_pursuit:ClosePursuit:C.Pitch](DEBUG): Initialize.
2013-06-03T23:11:38.431Z,1370301098.431 [homing_pursuit:ClosePursuit:D.Point] Running Loop=1
2013-06-03T23:11:38.431Z,1370301098.431 [homing_pursuit:ClosePursuit:D.Point](DEBUG): Initialize.
2013-06-03T23:11:38.822Z,1370301098.822 [homing_pursuit:ClosePursuit:C.Pitch] Running Loop=1
2013-06-03T23:11:38.827Z,1370301098.827 [homing_pursuit:ClosePursuit:B.SetSpeed] Running Loop=1
2013-06-03T23:11:38.833Z,1370301098.833 [homing_pursuit:ClosePursuit:A] Running Loop=1
2013-06-03T23:11:40.027Z,1370301100.027 [DAT](ERROR): Unexpected response:00:10:02.8822 , LVL= 15440, 12961, 16802, 25331, AGC=  63, IDX= 62-0.20,-1.912, PHS=-0.015, 0.149, 0.035, |v|= 0.091, RAW= 312.3,-22.1, CAL= 327.1,-25.4, ROT=  46.1,-25.4
Com
2013-06-03T23:11:40.443Z,1370301100.443 [DAT](FAULT): Parse Failed - Received:pass 169.1
Range 1 to 0 : 0.3 m
2013-06-03T23:11:40.826Z,1370301100.826 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:43.382Z,1370301103.382 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:45.954Z,1370301105.954 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:48.526Z,1370301108.526 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:51.086Z,1370301111.086 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:53.658Z,1370301113.658 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:56.226Z,1370301116.226 [DVL_micro](DEBUG): NQ1
2013-06-03T23:11:58.786Z,1370301118.786 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:01.362Z,1370301121.362 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:03.926Z,1370301123.926 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:06.490Z,1370301126.490 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:09.050Z,1370301129.050 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:11.606Z,1370301131.606 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:14.182Z,1370301134.182 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:16.750Z,1370301136.750 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:19.326Z,1370301139.326 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:21.894Z,1370301141.894 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:24.466Z,1370301144.466 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:27.034Z,1370301147.034 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:29.606Z,1370301149.606 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:32.158Z,1370301152.158 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:34.730Z,1370301154.730 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:37.315Z,1370301157.315 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:39.858Z,1370301159.858 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:42.418Z,1370301162.418 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:44.982Z,1370301164.982 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:47.554Z,1370301167.554 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:50.130Z,1370301170.130 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:52.702Z,1370301172.702 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:55.252Z,1370301175.252 [DVL_micro](DEBUG): NQ1
2013-06-03T23:12:57.830Z,1370301177.830 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:00.374Z,1370301180.374 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:02.954Z,1370301182.954 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:05.518Z,1370301185.518 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:08.078Z,1370301188.078 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:10.642Z,1370301190.642 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:13.206Z,1370301193.206 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:15.770Z,1370301195.770 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:18.350Z,1370301198.350 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:20.906Z,1370301200.906 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:23.474Z,1370301203.474 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:26.054Z,1370301206.054 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:28.634Z,1370301208.634 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:31.194Z,1370301211.194 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:33.750Z,1370301213.750 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:36.306Z,1370301216.306 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:38.865Z,1370301218.865 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:41.454Z,1370301221.454 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:44.006Z,1370301224.006 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:46.582Z,1370301226.582 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:49.146Z,1370301229.146 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:51.706Z,1370301231.706 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:54.262Z,1370301234.262 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:56.826Z,1370301236.826 [DVL_micro](DEBUG): NQ1
2013-06-03T23:13:59.374Z,1370301239.374 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:01.958Z,1370301241.958 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:04.538Z,1370301244.538 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:07.090Z,1370301247.090 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:09.646Z,1370301249.646 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:12.238Z,1370301252.238 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:14.794Z,1370301254.794 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:17.350Z,1370301257.350 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:19.906Z,1370301259.906 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:22.454Z,1370301262.454 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:25.022Z,1370301265.022 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:27.582Z,1370301267.582 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:30.142Z,1370301270.142 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:32.710Z,1370301272.710 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:35.254Z,1370301275.254 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:37.830Z,1370301277.830 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:40.394Z,1370301280.394 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:42.956Z,1370301282.956 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:45.510Z,1370301285.510 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:48.082Z,1370301288.082 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:50.638Z,1370301290.638 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:53.206Z,1370301293.206 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:55.758Z,1370301295.758 [DVL_micro](DEBUG): NQ1
2013-06-03T23:14:58.326Z,1370301298.326 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:00.878Z,1370301300.878 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:03.434Z,1370301303.434 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:05.995Z,1370301305.995 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:08.558Z,1370301308.558 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:11.110Z,1370301311.110 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:13.666Z,1370301313.666 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:16.222Z,1370301316.222 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:18.780Z,1370301318.780 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:21.338Z,1370301321.338 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:23.890Z,1370301323.890 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:26.462Z,1370301326.462 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:29.022Z,1370301329.022 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:31.594Z,1370301331.594 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:34.150Z,1370301334.150 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:36.706Z,1370301336.706 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:39.254Z,1370301339.254 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:41.818Z,1370301341.818 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:44.382Z,1370301344.382 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:46.938Z,1370301346.938 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:49.502Z,1370301349.502 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:52.054Z,1370301352.054 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:54.618Z,1370301354.618 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:57.182Z,1370301357.182 [DVL_micro](DEBUG): NQ1
2013-06-03T23:15:59.738Z,1370301359.738 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:02.310Z,1370301362.310 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:04.866Z,1370301364.866 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:07.410Z,1370301367.410 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:09.994Z,1370301369.994 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:12.550Z,1370301372.550 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:15.106Z,1370301375.106 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:17.654Z,1370301377.654 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:20.226Z,1370301380.226 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:22.794Z,1370301382.794 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:25.338Z,1370301385.338 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:27.894Z,1370301387.894 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:30.466Z,1370301390.466 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:33.022Z,1370301393.022 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:35.582Z,1370301395.582 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:38.138Z,1370301398.138 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:40.706Z,1370301400.706 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:43.242Z,1370301403.242 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:45.806Z,1370301405.806 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:48.354Z,1370301408.354 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:50.942Z,1370301410.942 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:53.498Z,1370301413.498 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:56.058Z,1370301416.058 [DVL_micro](DEBUG): NQ1
2013-06-03T23:16:58.606Z,1370301418.606 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:01.166Z,1370301421.166 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:03.750Z,1370301423.750 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:06.306Z,1370301426.306 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:08.865Z,1370301428.865 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:11.430Z,1370301431.430 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:13.993Z,1370301433.993 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:16.554Z,1370301436.554 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:19.106Z,1370301439.106 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:21.666Z,1370301441.666 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:24.242Z,1370301444.242 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:26.797Z,1370301446.796 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:29.342Z,1370301449.342 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:31.926Z,1370301451.926 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:34.482Z,1370301454.482 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:37.034Z,1370301457.034 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:39.597Z,1370301459.597 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:42.150Z,1370301462.150 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:44.719Z,1370301464.718 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:47.282Z,1370301467.282 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:49.830Z,1370301469.830 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:52.393Z,1370301472.393 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:54.954Z,1370301474.954 [DVL_micro](DEBUG): NQ1
2013-06-03T23:17:57.514Z,1370301477.514 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:00.078Z,1370301480.078 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:02.642Z,1370301482.642 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:05.206Z,1370301485.206 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:07.762Z,1370301487.762 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:10.322Z,1370301490.322 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:12.878Z,1370301492.878 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:15.443Z,1370301495.443 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:17.997Z,1370301497.997 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:20.558Z,1370301500.558 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:23.110Z,1370301503.110 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:25.670Z,1370301505.670 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:28.214Z,1370301508.214 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:30.781Z,1370301510.781 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:33.354Z,1370301513.354 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:35.922Z,1370301515.922 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:38.482Z,1370301518.482 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:41.042Z,1370301521.042 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:43.608Z,1370301523.608 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:46.150Z,1370301526.150 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:48.710Z,1370301528.710 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:51.246Z,1370301531.246 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:53.806Z,1370301533.806 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:56.354Z,1370301536.354 [DVL_micro](DEBUG): NQ1
2013-06-03T23:18:58.918Z,1370301538.918 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:01.466Z,1370301541.466 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:04.046Z,1370301544.046 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:06.594Z,1370301546.594 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:09.154Z,1370301549.154 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:11.742Z,1370301551.742 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:14.306Z,1370301554.306 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:16.878Z,1370301556.878 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:19.422Z,1370301559.422 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:21.990Z,1370301561.990 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:24.554Z,1370301564.554 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:27.118Z,1370301567.118 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:29.670Z,1370301569.670 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:32.230Z,1370301572.230 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:34.806Z,1370301574.806 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:37.346Z,1370301577.346 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:39.902Z,1370301579.902 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:42.454Z,1370301582.454 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:45.006Z,1370301585.006 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:47.570Z,1370301587.570 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:50.162Z,1370301590.162 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:52.726Z,1370301592.726 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:55.274Z,1370301595.274 [DVL_micro](DEBUG): NQ1
2013-06-03T23:19:57.830Z,1370301597.830 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:00.396Z,1370301600.396 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:02.942Z,1370301602.942 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:05.498Z,1370301605.498 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:08.051Z,1370301608.051 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:10.622Z,1370301610.622 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:13.175Z,1370301613.175 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:15.754Z,1370301615.754 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:18.344Z,1370301618.344 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:20.882Z,1370301620.882 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:23.458Z,1370301623.458 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:26.007Z,1370301626.007 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:28.562Z,1370301628.562 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:31.126Z,1370301631.126 [DVL_micro](DEBUG): NQ1
2013-06-03T23:20:33.686Z,1370301633.686 [AHRS_sp3003D](CRITICAL): SIGILL: illegal trap
2013-06-03T23:20:33.686Z,1370301633.686 [AHRS_sp3003D](CRITICAL): Bad address: 0x4033AE5C
2013-06-03T23:20:33.687Z,1370301633.687 [AHRS_sp3003D](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.687Z,1370301633.687 [AHRS_sp3003D] Software Fault, FailCount= 1
2013-06-03T23:20:33.687Z,1370301633.687 [AHRS_sp3003D](ERROR): Software Fault
2013-06-03T23:20:33.689Z,1370301633.690 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:33.690Z,1370301633.690 [AHRS_sp3003D](INFO): Thread ID is 1615
2013-06-03T23:20:33.694Z,1370301633.694 [Batt_Ocean_Server](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:33.697Z,1370301633.697 [DVL_micro](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:33.698Z,1370301633.698 [DVL_micro](CRITICAL): Bad address: 0x74634160
2013-06-03T23:20:33.698Z,1370301633.698 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.698Z,1370301633.698 [DVL_micro] Software Fault, FailCount= 1
2013-06-03T23:20:33.698Z,1370301633.698 [DVL_micro](ERROR): Software Fault
2013-06-03T23:20:33.703Z,1370301633.703 [DVL_micro](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:33.703Z,1370301633.703 [DVL_micro](CRITICAL): Bad address: 0x1
2013-06-03T23:20:33.704Z,1370301633.704 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.705Z,1370301633.704 [Batt_Ocean_Server](CRITICAL): Bad address: 0x355DF54C
2013-06-03T23:20:33.705Z,1370301633.705 [Batt_Ocean_Server](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.705Z,1370301633.705 [Batt_Ocean_Server] Software Fault, FailCount= 1
2013-06-03T23:20:33.705Z,1370301633.705 [Batt_Ocean_Server](ERROR): Software Fault
2013-06-03T23:20:33.706Z,1370301633.707 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:33.707Z,1370301633.707 [DVL_micro](INFO): Thread ID is 1616
2013-06-03T23:20:33.711Z,1370301633.711 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:33.711Z,1370301633.711 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:33.711Z,1370301633.711 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.714Z,1370301633.714 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:33.715Z,1370301633.715 [Batt_Ocean_Server](INFO): Thread ID is 1617
2013-06-03T23:20:33.718Z,1370301633.718 [Depth_Keller](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:33.719Z,1370301633.719 [Depth_Keller](CRITICAL): Bad address: 0x0
2013-06-03T23:20:33.719Z,1370301633.719 [Depth_Keller](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.719Z,1370301633.719 [Depth_Keller] Software Fault, FailCount= 1
2013-06-03T23:20:33.719Z,1370301633.719 [Depth_Keller](ERROR): Software Fault
2013-06-03T23:20:33.722Z,1370301633.722 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:33.723Z,1370301633.723 [DVL_micro](INFO): Thread ID is 1618
2013-06-03T23:20:33.727Z,1370301633.727 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:33.727Z,1370301633.727 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:33.727Z,1370301633.727 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.733Z,1370301633.733 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:33.733Z,1370301633.733 [Depth_Keller](INFO): Thread ID is 1619
2013-06-03T23:20:33.737Z,1370301633.737 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-06-03T23:20:33.764Z,1370301633.764 [DropWeight](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:33.765Z,1370301633.764 [DropWeight](CRITICAL): Bad address: 0x0
2013-06-03T23:20:33.765Z,1370301633.765 [DropWeight](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.765Z,1370301633.765 [DropWeight] Software Fault, FailCount= 1
2013-06-03T23:20:33.765Z,1370301633.765 [DropWeight](ERROR): Software Fault
2013-06-03T23:20:33.766Z,1370301633.766 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:33.767Z,1370301633.767 [DVL_micro](INFO): Thread ID is 1620
2013-06-03T23:20:33.771Z,1370301633.770 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:33.771Z,1370301633.771 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:33.771Z,1370301633.771 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.778Z,1370301633.778 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:33.779Z,1370301633.779 [DropWeight](INFO): Thread ID is 1622
2013-06-03T23:20:33.787Z,1370301633.787 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:33.788Z,1370301633.788 [DVL_micro](INFO): Thread ID is 1623
2013-06-03T23:20:33.803Z,1370301633.803 [NAL9602](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:33.803Z,1370301633.803 [NAL9602](CRITICAL): Bad address: 0x0
2013-06-03T23:20:33.803Z,1370301633.803 [NAL9602](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.803Z,1370301633.803 [NAL9602] Software Fault, FailCount= 1
2013-06-03T23:20:33.804Z,1370301633.804 [NAL9602](ERROR): Software Fault
2013-06-03T23:20:33.805Z,1370301633.805 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:33.805Z,1370301633.805 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:33.806Z,1370301633.806 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:33.807Z,1370301633.807 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:33.807Z,1370301633.807 [NAL9602](INFO): Thread ID is 1624
2013-06-03T23:20:33.814Z,1370301633.814 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:33.815Z,1370301633.815 [DVL_micro](INFO): Thread ID is 1625
2013-06-03T23:20:34.147Z,1370301634.148 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.148Z,1370301634.148 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.148Z,1370301634.148 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.149Z,1370301634.149 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.149Z,1370301634.149 [DVL_micro](INFO): Thread ID is 1626
2013-06-03T23:20:34.153Z,1370301634.153 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.154Z,1370301634.154 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.154Z,1370301634.154 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.162Z,1370301634.162 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.162Z,1370301634.162 [DVL_micro](INFO): Thread ID is 1627
2013-06-03T23:20:34.167Z,1370301634.167 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.167Z,1370301634.167 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.167Z,1370301634.167 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.170Z,1370301634.170 [Onboard](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:34.170Z,1370301634.170 [Onboard](CRITICAL): Bad address: 0x20747260
2013-06-03T23:20:34.171Z,1370301634.171 [Onboard](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.171Z,1370301634.171 [Onboard] Software Fault, FailCount= 1
2013-06-03T23:20:34.171Z,1370301634.171 [Onboard](ERROR): Software Fault
2013-06-03T23:20:34.174Z,1370301634.174 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.175Z,1370301634.174 [DVL_micro](INFO): Thread ID is 1628
2013-06-03T23:20:34.180Z,1370301634.180 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.180Z,1370301634.180 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.181Z,1370301634.181 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.185Z,1370301634.185 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:34.185Z,1370301634.185 [Onboard](INFO): Thread ID is 1629
2013-06-03T23:20:34.189Z,1370301634.189 [Radio_Freewave](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:34.190Z,1370301634.190 [Radio_Freewave](CRITICAL): Bad address: 0x0
2013-06-03T23:20:34.190Z,1370301634.190 [Radio_Freewave](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.190Z,1370301634.190 [Radio_Freewave] Software Fault, FailCount= 1
2013-06-03T23:20:34.190Z,1370301634.190 [Radio_Freewave](ERROR): Software Fault
2013-06-03T23:20:34.197Z,1370301634.197 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.197Z,1370301634.197 [DVL_micro](INFO): Thread ID is 1630
2013-06-03T23:20:34.215Z,1370301634.215 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.216Z,1370301634.216 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.216Z,1370301634.216 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.218Z,1370301634.217 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:34.218Z,1370301634.218 [Radio_Freewave](INFO): Thread ID is 1631
2013-06-03T23:20:34.230Z,1370301634.230 [DAT](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:34.230Z,1370301634.230 [DAT](CRITICAL): Bad address: 0x39685378
2013-06-03T23:20:34.230Z,1370301634.230 [DAT](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.231Z,1370301634.231 [DAT] Software Fault, FailCount= 1
2013-06-03T23:20:34.231Z,1370301634.231 [DAT](ERROR): Software Fault
2013-06-03T23:20:34.232Z,1370301634.232 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.232Z,1370301634.232 [DVL_micro](INFO): Thread ID is 1632
2013-06-03T23:20:34.236Z,1370301634.236 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.236Z,1370301634.236 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.237Z,1370301634.237 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.238Z,1370301634.238 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:34.239Z,1370301634.239 [DAT](INFO): Thread ID is 1633
2013-06-03T23:20:34.243Z,1370301634.243 [SCPI](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:34.243Z,1370301634.243 [SCPI](CRITICAL): Bad address: 0x0
2013-06-03T23:20:34.243Z,1370301634.243 [SCPI](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.243Z,1370301634.243 [SCPI] Software Fault, FailCount= 1
2013-06-03T23:20:34.243Z,1370301634.243 [SCPI](ERROR): Software Fault
2013-06-03T23:20:34.246Z,1370301634.246 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.247Z,1370301634.247 [DVL_micro](INFO): Thread ID is 1634
2013-06-03T23:20:34.251Z,1370301634.251 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.252Z,1370301634.251 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.252Z,1370301634.252 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.256Z,1370301634.256 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:34.256Z,1370301634.256 [SCPI](INFO): Thread ID is 1635
2013-06-03T23:20:34.286Z,1370301634.286 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.287Z,1370301634.286 [DVL_micro](INFO): Thread ID is 1636
2013-06-03T23:20:34.291Z,1370301634.291 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.291Z,1370301634.291 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.291Z,1370301634.291 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.314Z,1370301634.314 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.315Z,1370301634.315 [DVL_micro](INFO): Thread ID is 1637
2013-06-03T23:20:34.319Z,1370301634.319 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.319Z,1370301634.319 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.319Z,1370301634.319 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.333Z,1370301634.333 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.333Z,1370301634.333 [DVL_micro](INFO): Thread ID is 1638
2013-06-03T23:20:34.364Z,1370301634.364 [CBIT](ERROR): Software Fault in component: AHRS_sp3003D
2013-06-03T23:20:34.365Z,1370301634.365 [CBIT](ERROR): Software Fault in component: Batt_Ocean_Server
2013-06-03T23:20:34.365Z,1370301634.365 [CBIT](ERROR): Software Fault in component: Depth_Keller
2013-06-03T23:20:34.365Z,1370301634.365 [CBIT](INFO): Clearing failed state for component Depth_Keller
2013-06-03T23:20:34.365Z,1370301634.365 [Depth_Keller] No Fault, FailCount= 1
2013-06-03T23:20:34.366Z,1370301634.366 [CBIT](ERROR): Software Fault in component: DropWeight
2013-06-03T23:20:34.366Z,1370301634.366 [CBIT](CRITICAL): Software Fault in component: DropWeight
2013-06-03T23:20:34.366Z,1370301634.366 [CBIT](ERROR): Software Fault in component: DVL_micro
2013-06-03T23:20:34.367Z,1370301634.367 [CBIT](ERROR): Software Fault in component: NAL9602
2013-06-03T23:20:34.367Z,1370301634.367 [CBIT](ERROR): Software Fault in component: Onboard
2013-06-03T23:20:34.367Z,1370301634.367 [CBIT](CRITICAL): Software Fault in component: Onboard
2013-06-03T23:20:34.368Z,1370301634.368 [CBIT](ERROR): Software Fault in component: Radio_Freewave
2013-06-03T23:20:34.368Z,1370301634.368 [CBIT](ERROR): Software Fault in component: DAT
2013-06-03T23:20:34.368Z,1370301634.368 [CBIT](ERROR): Software Fault in component: SCPI
2013-06-03T23:20:34.668Z,1370301634.668 [AHRS_sp3003D](CRITICAL): SIGSEGV: address not mapped to object
2013-06-03T23:20:34.668Z,1370301634.668 [AHRS_sp3003D](CRITICAL): Bad address: 0x0
2013-06-03T23:20:34.668Z,1370301634.668 [AHRS_sp3003D](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.669Z,1370301634.669 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-06-03T23:20:34.669Z,1370301634.670 [AHRS_sp3003D](INFO): Thread ID is 1639
2013-06-03T23:20:34.718Z,1370301634.718 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.718Z,1370301634.718 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.719Z,1370301634.718 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.734Z,1370301634.734 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.734Z,1370301634.734 [DVL_micro](INFO): Thread ID is 1640
2013-06-03T23:20:34.738Z,1370301634.738 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.738Z,1370301634.739 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.739Z,1370301634.739 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.763Z,1370301634.763 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.763Z,1370301634.763 [DVL_micro](INFO): Thread ID is 1641
2013-06-03T23:20:34.771Z,1370301634.771 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.771Z,1370301634.771 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.771Z,1370301634.772 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.806Z,1370301634.806 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.807Z,1370301634.806 [DVL_micro](INFO): Thread ID is 1642
2013-06-03T23:20:34.811Z,1370301634.811 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.811Z,1370301634.811 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.811Z,1370301634.811 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.842Z,1370301634.842 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.842Z,1370301634.842 [DVL_micro](INFO): Thread ID is 1643
2013-06-03T23:20:34.846Z,1370301634.846 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.846Z,1370301634.846 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.847Z,1370301634.847 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.870Z,1370301634.870 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.871Z,1370301634.871 [DVL_micro](INFO): Thread ID is 1644
2013-06-03T23:20:34.875Z,1370301634.875 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.875Z,1370301634.875 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.875Z,1370301634.875 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.906Z,1370301634.906 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.906Z,1370301634.906 [DVL_micro](INFO): Thread ID is 1645
2013-06-03T23:20:34.911Z,1370301634.911 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.911Z,1370301634.911 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.911Z,1370301634.911 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.934Z,1370301634.934 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.934Z,1370301634.934 [DVL_micro](INFO): Thread ID is 1646
2013-06-03T23:20:34.938Z,1370301634.938 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.939Z,1370301634.939 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.939Z,1370301634.939 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.958Z,1370301634.958 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.958Z,1370301634.958 [DVL_micro](INFO): Thread ID is 1647
2013-06-03T23:20:34.962Z,1370301634.962 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.963Z,1370301634.963 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.963Z,1370301634.963 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:34.982Z,1370301634.982 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:34.982Z,1370301634.982 [DVL_micro](INFO): Thread ID is 1648
2013-06-03T23:20:34.986Z,1370301634.986 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:34.987Z,1370301634.987 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:34.987Z,1370301634.987 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:35.014Z,1370301635.014 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:35.014Z,1370301635.014 [DVL_micro](INFO): Thread ID is 1649
2013-06-03T23:20:35.019Z,1370301635.019 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:35.019Z,1370301635.019 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:35.019Z,1370301635.019 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:35.046Z,1370301635.046 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:35.046Z,1370301635.046 [DVL_micro](INFO): Thread ID is 1650
2013-06-03T23:20:35.050Z,1370301635.050 [DVL_micro](CRITICAL): SIGILL: illegal opcode
2013-06-03T23:20:35.051Z,1370301635.050 [DVL_micro](CRITICAL): Bad address: 0x1D8B74
2013-06-03T23:20:35.051Z,1370301635.051 [DVL_micro](CRITICAL): Backtrace: 
/mnt/mmc/LRAUV/bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x157b00]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2013-06-03T23:20:35.074Z,1370301635.074 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2013-06-03T23:20:35.075Z,1370301635.075 [DVL_micro](INFO): Thread ID is 1651
2013-06-03T23:20:35.079Z,1370301635.079 [DVL_micro](CRITICAL): SIGILL: illegal opcode