2009-11-30T03:16:01.558Z,1259550961.558 [Supervisor](DEBUG): Initializing supervisor.
2009-11-30T03:16:01.563Z,1259550961.563 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2009-11-30T03:16:01.564Z,1259550961.564 [SyncHandler](INFO): Protected caller Thread ID is 2568
2009-11-30T03:16:01.564Z,1259550961.564 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2009-11-30T03:16:01.565Z,1259550961.565 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2009-11-30T03:16:01.565Z,1259550961.565 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2569
2009-11-30T03:16:01.570Z,1259550961.570 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2009-11-30T03:16:01.588Z,1259550961.588 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2009-11-30T03:16:01.589Z,1259550961.589 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2009-11-30T03:16:01.589Z,1259550961.589 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2570
2009-11-30T03:16:01.593Z,1259550961.593 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2009-11-30T03:16:01.594Z,1259550961.594 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2009-11-30T03:16:01.595Z,1259550961.595 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2571
2009-11-30T03:16:01.597Z,1259550961.597 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2009-11-30T03:16:01.597Z,1259550961.597 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2009-11-30T03:16:01.598Z,1259550961.598 [logger ThreadHandler](INFO): Protected caller Thread ID is 2572
2009-11-30T03:16:01.601Z,1259550961.601 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2009-11-30T03:16:01.602Z,1259550961.602 [Supervisor](INFO): Looking for Config files in directory: Config/
2009-11-30T03:16:01.603Z,1259550961.603 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2009-11-30T03:16:01.704Z,1259550961.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2009-11-30T03:16:01.705Z,1259550961.705 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2009-11-30T03:16:01.932Z,1259550961.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2009-11-30T03:16:01.932Z,1259550961.932 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2009-11-30T03:16:02.194Z,1259550962.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2009-11-30T03:16:02.194Z,1259550962.194 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2009-11-30T03:16:02.305Z,1259550962.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2009-11-30T03:16:02.305Z,1259550962.305 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2009-11-30T03:16:02.979Z,1259550962.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2009-11-30T03:16:02.979Z,1259550962.979 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2009-11-30T03:16:03.440Z,1259550963.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2009-11-30T03:16:03.441Z,1259550963.441 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2009-11-30T03:16:04.016Z,1259550964.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2009-11-30T03:16:04.017Z,1259550964.017 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2009-11-30T03:16:04.408Z,1259550964.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2009-11-30T03:16:04.408Z,1259550964.408 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2009-11-30T03:16:04.745Z,1259550964.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2009-11-30T03:16:04.745Z,1259550964.745 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2009-11-30T03:16:05.141Z,1259550965.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2009-11-30T03:16:05.141Z,1259550965.141 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2009-11-30T03:16:05.366Z,1259550965.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2009-11-30T03:16:05.367Z,1259550965.367 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2009-11-30T03:16:05.498Z,1259550965.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2009-11-30T03:16:05.499Z,1259550965.499 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2009-11-30T03:16:05.581Z,1259550965.581 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2009-11-30T03:16:05.663Z,1259550965.663 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2009-11-30T03:16:05.858Z,1259550965.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2009-11-30T03:16:05.858Z,1259550965.858 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2009-11-30T03:16:06.081Z,1259550966.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2009-11-30T03:16:06.083Z,1259550966.083 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2009-11-30T03:16:06.084Z,1259550966.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2009-11-30T03:16:06.201Z,1259550966.201 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2009-11-30T03:16:06.712Z,1259550966.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2009-11-30T03:16:06.713Z,1259550966.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2009-11-30T03:16:06.877Z,1259550966.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2009-11-30T03:16:07.217Z,1259550967.217 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2009-11-30T03:16:07.424Z,1259550967.424 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2009-11-30T03:16:07.512Z,1259550967.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2009-11-30T03:16:07.621Z,1259550967.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2009-11-30T03:16:07.725Z,1259550967.725 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2009-11-30T03:16:07.888Z,1259550967.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2009-11-30T03:16:07.993Z,1259550967.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2009-11-30T03:16:08.096Z,1259550968.096 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2009-11-30T03:16:08.104Z,1259550968.104 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2009-11-30T03:16:08.251Z,1259550968.251 [InternalSim] Loaded
2009-11-30T03:16:08.252Z,1259550968.252 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2009-11-30T03:16:08.252Z,1259550968.252 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2009-11-30T03:16:08.253Z,1259550968.253 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2009-11-30T03:16:08.379Z,1259550968.379 [BuoyancyServo] Loaded
2009-11-30T03:16:08.379Z,1259550968.379 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2009-11-30T03:16:08.397Z,1259550968.397 [MassServo] Loaded
2009-11-30T03:16:08.397Z,1259550968.397 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2009-11-30T03:16:08.412Z,1259550968.412 [ThrusterHE] Loaded
2009-11-30T03:16:08.412Z,1259550968.412 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2009-11-30T03:16:08.413Z,1259550968.413 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2009-11-30T03:16:08.413Z,1259550968.413 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2009-11-30T03:16:08.556Z,1259550968.556 [SBIT](DEBUG): Construct Startup Built In Test.
2009-11-30T03:16:08.570Z,1259550968.570 [SBIT] Loaded
2009-11-30T03:16:08.570Z,1259550968.570 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2009-11-30T03:16:08.573Z,1259550968.573 [IBIT](DEBUG): Construct Initiated Built In Test.
2009-11-30T03:16:08.591Z,1259550968.591 [IBIT] Loaded
2009-11-30T03:16:08.591Z,1259550968.591 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2009-11-30T03:16:08.597Z,1259550968.597 [CBIT](DEBUG): Construct Continuous Built In Test.
2009-11-30T03:16:08.807Z,1259550968.807 [CBIT] Loaded
2009-11-30T03:16:08.808Z,1259550968.808 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2009-11-30T03:16:08.808Z,1259550968.808 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2009-11-30T03:16:08.809Z,1259550968.809 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2009-11-30T03:16:08.978Z,1259550968.978 [CTD_Seabird] Loaded
2009-11-30T03:16:08.978Z,1259550968.978 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2009-11-30T03:16:08.979Z,1259550968.979 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2009-11-30T03:16:08.980Z,1259550968.980 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2654
2009-11-30T03:16:09.010Z,1259550969.010 [ESPComponent] Loaded
2009-11-30T03:16:09.010Z,1259550969.010 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2009-11-30T03:16:09.029Z,1259550969.029 [PAR_Licor] Loaded
2009-11-30T03:16:09.029Z,1259550969.029 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2009-11-30T03:16:09.030Z,1259550969.030 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2009-11-30T03:16:09.031Z,1259550969.031 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2009-11-30T03:16:09.082Z,1259550969.082 [DepthRateCalculator] Loaded
2009-11-30T03:16:09.083Z,1259550969.083 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2009-11-30T03:16:09.088Z,1259550969.088 [PitchRateCalculator] Loaded
2009-11-30T03:16:09.088Z,1259550969.088 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2009-11-30T03:16:09.098Z,1259550969.098 [SpeedCalculator] Loaded
2009-11-30T03:16:09.098Z,1259550969.098 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2009-11-30T03:16:09.103Z,1259550969.103 [YawRateCalculator] Loaded
2009-11-30T03:16:09.103Z,1259550969.103 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2009-11-30T03:16:09.121Z,1259550969.121 [ElevatorOffsetCalculator] Loaded
2009-11-30T03:16:09.122Z,1259550969.122 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2009-11-30T03:16:09.122Z,1259550969.122 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2009-11-30T03:16:09.123Z,1259550969.123 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2009-11-30T03:16:09.219Z,1259550969.219 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2009-11-30T03:16:09.220Z,1259550969.220 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2009-11-30T03:16:09.247Z,1259550969.247 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2009-11-30T03:16:09.248Z,1259550969.248 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2009-11-30T03:16:09.359Z,1259550969.359 [DeadReckonUsingMultipleVelocitySources] Loaded
2009-11-30T03:16:09.359Z,1259550969.359 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2009-11-30T03:16:09.372Z,1259550969.372 [NavChart] Loaded
2009-11-30T03:16:09.372Z,1259550969.372 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2009-11-30T03:16:09.378Z,1259550969.378 [UniversalFixResidualReporter] Loaded
2009-11-30T03:16:09.378Z,1259550969.378 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2009-11-30T03:16:09.379Z,1259550969.379 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2009-11-30T03:16:09.379Z,1259550969.379 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2009-11-30T03:16:10.457Z,1259550970.457 [BPC1] Loaded
2009-11-30T03:16:10.457Z,1259550970.457 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2009-11-30T03:16:10.532Z,1259550970.532 [DataOverHttps] Loaded
2009-11-30T03:16:10.532Z,1259550970.532 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2009-11-30T03:16:10.533Z,1259550970.533 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2009-11-30T03:16:10.534Z,1259550970.534 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2655
2009-11-30T03:16:10.554Z,1259550970.554 [Depth_Keller] Loaded
2009-11-30T03:16:10.554Z,1259550970.554 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2009-11-30T03:16:10.559Z,1259550970.559 [DropWeight] Loaded
2009-11-30T03:16:10.559Z,1259550970.559 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2009-11-30T03:16:10.616Z,1259550970.616 [NAL9602] Loaded
2009-11-30T03:16:10.616Z,1259550970.616 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2009-11-30T03:16:10.641Z,1259550970.641 [Onboard] Loaded
2009-11-30T03:16:10.641Z,1259550970.641 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2009-11-30T03:16:10.642Z,1259550970.642 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0
2009-11-30T03:16:10.643Z,1259550970.643 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2656
2009-11-30T03:16:10.654Z,1259550970.654 [Power24vConverter] Loaded
2009-11-30T03:16:10.654Z,1259550970.654 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2009-11-30T03:16:10.667Z,1259550970.667 [Radio_Surface] Loaded
2009-11-30T03:16:10.667Z,1259550970.667 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2009-11-30T03:16:10.668Z,1259550970.668 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A3D4E0
2009-11-30T03:16:10.669Z,1259550970.669 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2657
2009-11-30T03:16:10.670Z,1259550970.670 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2009-11-30T03:16:10.670Z,1259550970.670 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2009-11-30T03:16:10.729Z,1259550970.729 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2009-11-30T03:16:10.729Z,1259550970.729 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2009-11-30T03:16:10.782Z,1259550970.782 [VerticalControl](DEBUG): Construct VerticalControl.
2009-11-30T03:16:10.832Z,1259550970.832 [VerticalControl] Loaded
2009-11-30T03:16:10.832Z,1259550970.832 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2009-11-30T03:16:10.835Z,1259550970.835 [HorizontalControl](DEBUG): Construct HorizontalControl.
2009-11-30T03:16:10.869Z,1259550970.869 [HorizontalControl] Loaded
2009-11-30T03:16:10.870Z,1259550970.870 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2009-11-30T03:16:10.872Z,1259550970.872 [SpeedControl](DEBUG): Construct SpeedControl.
2009-11-30T03:16:10.874Z,1259550970.874 [SpeedControl] Loaded
2009-11-30T03:16:10.874Z,1259550970.874 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2009-11-30T03:16:10.877Z,1259550970.877 [LoopControl](DEBUG): Construct LoopControl.
2009-11-30T03:16:10.877Z,1259550970.877 [LoopControl] Loaded
2009-11-30T03:16:10.878Z,1259550970.878 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2009-11-30T03:16:10.878Z,1259550970.878 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2009-11-30T03:16:10.878Z,1259550970.878 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2009-11-30T03:16:10.889Z,1259550970.889 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2009-11-30T03:16:10.889Z,1259550970.889 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2009-11-30T03:16:11.202Z,1259550971.202 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2009-11-30T03:16:11.268Z,1259550971.268 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2009-11-30T03:16:11.270Z,1259550971.270 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2009-11-30T03:16:11.312Z,1259550971.312 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2009-11-30T03:16:11.313Z,1259550971.313 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B474E0
2009-11-30T03:16:11.313Z,1259550971.313 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2658
2009-11-30T03:16:11.319Z,1259550971.319 [Supervisor](INFO): Main Thread ID is 2567
2009-11-30T03:16:11.319Z,1259550971.319 [Supervisor](DEBUG): Running supervisor.
2009-11-30T03:16:11.319Z,1259550971.319 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2659
2009-11-30T03:16:11.320Z,1259550971.320 [CommandExec](INFO): Initializing the command executive.
2009-11-30T03:16:11.360Z,1259550971.360 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2660
2009-11-30T03:16:11.380Z,1259550971.380 [controlThread ThreadHandler](INFO): Handler Thread ID is 2661
2009-11-30T03:16:11.381Z,1259550971.381 [controlThread](DEBUG): Initializing ControlThread
2009-11-30T03:16:11.381Z,1259550971.381 [InternalSim](DEBUG):  InternalSim initializing...
2009-11-30T03:16:11.388Z,1259550971.388 [InternalSim](DEBUG): oceanModelVarCount=0
2009-11-30T03:16:11.409Z,1259550971.409 [logger ThreadHandler](INFO): Handler Thread ID is 2662
2009-11-30T03:16:11.409Z,1259550971.409 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2663
2009-11-30T03:16:11.411Z,1259550971.411 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2664
2009-11-30T03:16:11.417Z,1259550971.417 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2009-11-30T03:16:11.448Z,1259550971.448 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2009-11-30T03:16:11.450Z,1259550971.450 [Onboard ThreadHandler](INFO): Handler Thread ID is 2666
2009-11-30T03:16:11.483Z,1259550971.483 [SBIT](INFO): Initialize SBIT Component.
2009-11-30T03:16:11.484Z,1259550971.484 [SBIT](IMPORTANT): git: 2022-04-13-30-gee09099fd
2009-11-30T03:16:11.484Z,1259550971.484 [SBIT](INFO): git hash: ee09099fdb3cc7f818252a020ea252f0a2301845
2009-11-30T03:16:11.484Z,1259550971.484 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2009-11-30T03:16:11.485Z,1259550971.485 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2667
2009-11-30T03:16:11.493Z,1259550971.493 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2009-11-30T03:16:11.494Z,1259550971.494 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2009-11-30T03:16:11.494Z,1259550971.494 [IBIT](INFO): Initialize IBIT Component.
2009-11-30T03:16:11.495Z,1259550971.495 [CBIT](DEBUG): Initialize CBIT Component.
2009-11-30T03:16:11.496Z,1259550971.496 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2668
2009-11-30T03:16:11.506Z,1259550971.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2009-11-30T03:16:11.506Z,1259550971.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2009-11-30T03:16:11.507Z,1259550971.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2009-11-30T03:16:11.507Z,1259550971.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2009-11-30T03:16:11.507Z,1259550971.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000
2009-11-30T03:16:11.507Z,1259550971.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000
2009-11-30T03:16:11.507Z,1259550971.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000
2009-11-30T03:16:11.507Z,1259550971.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000
2009-11-30T03:16:11.508Z,1259550971.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000
2009-11-30T03:16:11.508Z,1259550971.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000
2009-11-30T03:16:11.508Z,1259550971.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000
2009-11-30T03:16:11.508Z,1259550971.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000
2009-11-30T03:16:11.512Z,1259550971.512 [CBIT](DEBUG): Initialized mux pins.
2009-11-30T03:16:11.512Z,1259550971.512 [CBIT](DEBUG): Initializing the watchdog timer.
2009-11-30T03:16:11.540Z,1259550971.540 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2009-11-30T03:16:11.540Z,1259550971.540 [CBIT](DEBUG): Initializing heartbeat.
2009-11-30T03:16:11.612Z,1259550971.612 [CBIT](DEBUG): Deactivating GF circuits.
2009-11-30T03:16:11.612Z,1259550971.612 [CBIT](DEBUG): Deactivating emergency mode.
2009-11-30T03:16:11.648Z,1259550971.648 [CBIT](DEBUG): Backplane powered.
2009-11-30T03:16:11.652Z,1259550971.652 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2009-11-30T03:16:11.653Z,1259550971.653 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2009-11-30T03:16:11.653Z,1259550971.653 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2009-11-30T03:16:11.653Z,1259550971.653 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2009-11-30T03:16:11.654Z,1259550971.654 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2009-11-30T03:16:11.655Z,1259550971.655 [NavChart](DEBUG): Initialize NavChart Navigation.
2009-11-30T03:16:11.655Z,1259550971.655 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2009-11-30T03:16:11.662Z,1259550971.662 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2009-11-30T03:16:11.663Z,1259550971.663 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2009-11-30T03:16:11.672Z,1259550971.672 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2009-11-30T03:16:11.673Z,1259550971.673 [LoopControl](DEBUG): Initialize LoopControlComponent.
2009-11-30T03:16:11.674Z,1259550971.674 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2009-11-30T03:16:11.711Z,1259550971.711 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2009-11-30T03:16:11.763Z,1259550971.763 [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://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2009-11-30T03:16:11.763Z,1259550971.763 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2009-11-30T03:16:11.846Z,1259550971.846 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2009-11-30T03:16:11.847Z,1259550971.847 [Default:A.Wait](DEBUG): Construct Wait.
2009-11-30T03:16:11.865Z,1259550971.865 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2009-11-30T03:16:11.885Z,1259550971.885 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2009-11-30T03:16:11.888Z,1259550971.888 [Radio_Surface](INFO): Powering up
2009-11-30T03:16:11.924Z,1259550971.924 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2009-11-30T03:16:11.930Z,1259550971.930 [Default:E.Execute](DEBUG): Construct Execute.
2009-11-30T03:16:11.954Z,1259550971.954 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

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

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2009-11-30T03:16:11.958Z,1259550971.958 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,ESPComponent,PAR_Licor,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2009-11-30T03:16:12.049Z,1259550972.049 [Depth_Keller](ERROR): Pressure reading out of range: 1896.040405 decibar
2009-11-30T03:16:12.050Z,1259550972.050 [Power24vConverter](INFO): Powering up.
2009-11-30T03:16:12.086Z,1259550972.086 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:16:12.092Z,1259550972.092 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2009-11-30T03:16:12.093Z,1259550972.093 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:16:12.100Z,1259550972.100 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:16:12.101Z,1259550972.101 [ThrusterHE](DEBUG): Initializing EZServoServo.
2009-11-30T03:16:12.108Z,1259550972.108 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2009-11-30T03:16:12.446Z,1259550972.446 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2009-11-30T03:16:12.446Z,1259550972.446 [DropWeight] Hardware Fault, FailCount= 1
2009-11-30T03:16:12.446Z,1259550972.446 [DropWeight](ERROR): Hardware Fault
2009-11-30T03:16:12.492Z,1259550972.492 [CommandExec](FAULT): Scheduling is paused
2009-11-30T03:16:12.493Z,1259550972.493 [CBIT](INFO): Critical error at 20091130T031612
2009-11-30T03:16:12.493Z,1259550972.493 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2009-11-30T03:16:12.495Z,1259550972.495 [CBIT](ERROR): Hardware Fault in component: DropWeight
2009-11-30T03:16:12.504Z,1259550972.504 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2009-11-30T03:16:12.826Z,1259550972.826 [CBIT](INFO): Critical error at 20091130T031612
2009-11-30T03:16:16.568Z,1259550976.568 [MassServo](FAULT): Overload Error
2009-11-30T03:16:16.568Z,1259550976.568 [MassServo] Hardware Fault, FailCount= 1
2009-11-30T03:16:16.568Z,1259550976.568 [MassServo](ERROR): Hardware Fault
2009-11-30T03:16:16.572Z,1259550976.572 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:16:16.858Z,1259550976.858 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:16:16.858Z,1259550976.858 [MassServo](INFO): Powering down
2009-11-30T03:16:17.812Z,1259550977.812 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:16:17.933Z,1259550977.933 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:16:17.934Z,1259550977.934 [ThrusterHE](ERROR): Zero Speed Commanded.
2009-11-30T03:16:17.999Z,1259550977.999 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:16:17.999Z,1259550977.999 [MassServo] No Fault, FailCount= 1
2009-11-30T03:16:21.796Z,1259550981.796 [MassServo](FAULT): Overload Error
2009-11-30T03:16:21.796Z,1259550981.796 [MassServo] Hardware Fault, FailCount= 2
2009-11-30T03:16:21.796Z,1259550981.796 [MassServo](ERROR): Hardware Fault
2009-11-30T03:16:21.800Z,1259550981.800 [CBIT](CRITICAL): Environmental Failure. Press:14.710511 PSI. Humidity:40%. Temp:24 C. ABORTING MISSION
2009-11-30T03:16:21.801Z,1259550981.801 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:16:22.121Z,1259550982.121 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:16:22.121Z,1259550982.121 [MassServo](INFO): Powering down
2009-11-30T03:16:22.123Z,1259550982.123 [CBIT](INFO): Critical error at 20091130T031621
2009-11-30T03:16:22.909Z,1259550982.909 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:16:23.029Z,1259550983.029 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:16:23.033Z,1259550983.033 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:16:23.033Z,1259550983.033 [MassServo] No Fault, FailCount= 2
2009-11-30T03:16:24.610Z,1259550984.610 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004752
2009-11-30T03:16:25.504Z,1259550985.504 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2009-11-30T03:16:25.504Z,1259550985.504 [CTD_Seabird] Communications Fault, FailCount= 1
2009-11-30T03:16:25.504Z,1259550985.504 [CTD_Seabird](ERROR): Communications Fault
2009-11-30T03:16:25.779Z,1259550985.779 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2009-11-30T03:16:25.909Z,1259550985.909 [CTD_Seabird](INFO): Powering down
2009-11-30T03:16:26.984Z,1259550986.984 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2009-11-30T03:16:26.984Z,1259550986.984 [CTD_Seabird] No Fault, FailCount= 1
2009-11-30T03:16:27.252Z,1259550987.252 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2009-11-30T03:16:27.388Z,1259550987.388 [MassServo](FAULT): Overload Error
2009-11-30T03:16:27.388Z,1259550987.388 [MassServo] Hardware Fault, FailCount= 3
2009-11-30T03:16:27.388Z,1259550987.388 [MassServo](ERROR): Hardware Fault
2009-11-30T03:16:27.392Z,1259550987.392 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:16:27.776Z,1259550987.776 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:16:27.776Z,1259550987.776 [MassServo](INFO): Powering down
2009-11-30T03:16:28.581Z,1259550988.581 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:16:28.701Z,1259550988.701 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:16:28.705Z,1259550988.705 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:16:28.705Z,1259550988.705 [MassServo] No Fault, FailCount= 3
2009-11-30T03:16:33.064Z,1259550993.064 [MassServo](FAULT): Overload Error
2009-11-30T03:16:33.064Z,1259550993.064 [MassServo] Hardware Fault, FailCount= 4
2009-11-30T03:16:33.064Z,1259550993.064 [MassServo](ERROR): Hardware Fault
2009-11-30T03:16:33.068Z,1259550993.068 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:16:33.413Z,1259550993.413 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:16:33.413Z,1259550993.413 [MassServo](INFO): Powering down
2009-11-30T03:16:34.203Z,1259550994.203 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:16:34.317Z,1259550994.317 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:16:34.321Z,1259550994.321 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:16:34.321Z,1259550994.321 [MassServo] No Fault, FailCount= 4
2009-11-30T03:16:38.720Z,1259550998.720 [MassServo](FAULT): Overload Error
2009-11-30T03:16:38.720Z,1259550998.720 [MassServo] Hardware Fault, FailCount= 5
2009-11-30T03:16:38.720Z,1259550998.720 [MassServo](ERROR): Hardware Fault
2009-11-30T03:16:38.724Z,1259550998.724 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:16:38.724Z,1259550998.724 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2009-11-30T03:16:39.089Z,1259550999.089 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:16:39.089Z,1259550999.089 [MassServo](INFO): Powering down
2009-11-30T03:16:39.091Z,1259550999.091 [CBIT](INFO): Critical error at 20091130T031638
2009-11-30T03:16:40.137Z,1259551000.137 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:16:40.257Z,1259551000.257 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:16:40.304Z,1259551000.304 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2009-11-30T03:16:40.304Z,1259551000.304 [CTD_Seabird] Communications Fault, FailCount= 2
2009-11-30T03:16:40.304Z,1259551000.304 [CTD_Seabird](ERROR): Communications Fault
2009-11-30T03:16:40.681Z,1259551000.681 [NAL9602](INFO): Powering up NAL9602
2009-11-30T03:16:40.708Z,1259551000.708 [CTD_Seabird](INFO): Powering down
2009-11-30T03:16:40.733Z,1259551000.733 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2009-11-30T03:16:41.918Z,1259551001.918 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2009-11-30T03:16:41.919Z,1259551001.919 [CTD_Seabird] No Fault, FailCount= 2
2009-11-30T03:16:42.048Z,1259551002.048 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2009-11-30T03:16:51.595Z,1259551011.595 [NAL9602](INFO): NAL9602 initialized
2009-11-30T03:16:54.048Z,1259551014.048 [CommandExec](IMPORTANT): got command failComponent
2009-11-30T03:16:54.049Z,1259551014.049 [CommandExec](IMPORTANT): Failed components:
2009-11-30T03:16:54.049Z,1259551014.049 [CommandExec](IMPORTANT): MassServo: Hardware Fault
2009-11-30T03:16:54.049Z,1259551014.049 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2009-11-30T03:16:55.100Z,1259551015.100 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2009-11-30T03:16:55.100Z,1259551015.100 [CTD_Seabird] Communications Fault, FailCount= 3
2009-11-30T03:16:55.100Z,1259551015.100 [CTD_Seabird](ERROR): Communications Fault
2009-11-30T03:16:55.262Z,1259551015.262 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2009-11-30T03:16:55.263Z,1259551015.263 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2009-11-30T03:16:55.504Z,1259551015.504 [CTD_Seabird](INFO): Powering down
2009-11-30T03:17:11.009Z,1259551031.009 [SBIT](IMPORTANT): Beginning Startup BIT
2009-11-30T03:17:11.014Z,1259551031.014 [CBIT](IMPORTANT): Beginning ground fault scan
2009-11-30T03:17:17.849Z,1259551037.849 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2009-11-30T03:17:17.850Z,1259551037.850 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2009-11-30T03:17:19.470Z,1259551039.470 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2009-11-30T03:17:21.925Z,1259551041.925 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006232
CHAN A1 (24V): 0.000640
CHAN A2 (12V): -0.005944
CHAN A3 (5V): -0.002258
CHAN B0 (3.3V): -0.001490
CHAN B1 (3.15aV): -0.000529
CHAN B2 (3.15bV): -0.000867
CHAN B3 (GND): -0.000366
OPEN: -0.000579
Full Scale: +/- 1 mA
2009-11-30T03:17:29.165Z,1259551049.165 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2009-11-30T03:17:29.166Z,1259551049.166 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2009-11-30T03:17:32.802Z,1259551052.802 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2009-11-30T03:17:40.874Z,1259551060.874 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2009-11-30T03:17:40.876Z,1259551060.876 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2009-11-30T03:17:46.130Z,1259551066.130 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2009-11-30T03:17:52.186Z,1259551072.186 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2009-11-30T03:17:52.188Z,1259551072.188 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2009-11-30T03:17:59.466Z,1259551079.466 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2009-11-30T03:18:03.909Z,1259551083.909 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2009-11-30T03:18:03.910Z,1259551083.910 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2009-11-30T03:18:05.146Z,1259551085.146 [SBIT](IMPORTANT): SBIT PASSED
2009-11-30T03:18:05.147Z,1259551085.147 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2009-11-30T03:18:05.147Z,1259551085.147 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2009-11-30T03:18:05.147Z,1259551085.147 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2009-11-30T03:18:05.148Z,1259551085.148 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=0 bool;

2009-11-30T03:18:05.148Z,1259551085.148 [SBIT](IMPORTANT): InternalSim.loadAtStartup=1 bool;

2009-11-30T03:18:05.148Z,1259551085.148 [SBIT](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2009-11-30T03:18:05.148Z,1259551085.148 [SBIT](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2009-11-30T03:18:05.148Z,1259551085.148 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2009-11-30T03:18:05.533Z,1259551085.533 [MissionManager](IMPORTANT): Started mission Startup
2009-11-30T03:18:05.534Z,1259551085.534 [Startup] Running Loop=1
2009-11-30T03:18:05.534Z,1259551085.534 [Startup](DEBUG): Aggregate::initialize Startup
2009-11-30T03:18:05.534Z,1259551085.534 [Startup:A.GoToSurface] Running Loop=1
2009-11-30T03:18:05.534Z,1259551085.534 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2009-11-30T03:18:05.535Z,1259551085.535 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2009-11-30T03:18:05.535Z,1259551085.535 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2009-11-30T03:18:05.535Z,1259551085.535 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2009-11-30T03:18:05.536Z,1259551085.536 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2009-11-30T03:18:05.536Z,1259551085.536 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2009-11-30T03:18:05.538Z,1259551085.538 [Startup:StartupSatComms] Running Loop=1
2009-11-30T03:18:05.538Z,1259551085.538 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2009-11-30T03:18:05.538Z,1259551085.538 [Startup:StartupSatComms:A] Running Loop=1
2009-11-30T03:18:05.939Z,1259551085.939 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2009-11-30T03:18:11.997Z,1259551091.997 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2009-11-30T03:18:11.997Z,1259551091.997 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2009-11-30T03:18:11.997Z,1259551091.997 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2009-11-30T03:18:12.007Z,1259551092.007 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2009-11-30T03:18:12.473Z,1259551092.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2009-11-30T03:18:12.473Z,1259551092.473 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2009-11-30T03:18:12.791Z,1259551092.791 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2009-11-30T03:18:15.214Z,1259551095.214 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2009-11-30T03:18:15.215Z,1259551095.215 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2009-11-30T03:18:26.170Z,1259551106.170 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2009-11-30T03:18:26.533Z,1259551106.533 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2009-11-30T03:18:26.534Z,1259551106.534 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2009-11-30T03:18:26.548Z,1259551106.548 [BPC1](FAULT): Failed to parse data from all battery packs.
2009-11-30T03:18:26.548Z,1259551106.548 [BPC1] Data Fault, FailCount= 1
2009-11-30T03:18:26.548Z,1259551106.548 [BPC1](ERROR): Data Fault
2009-11-30T03:18:26.610Z,1259551106.610 [CBIT](ERROR): Data Fault in component: BPC1
2009-11-30T03:19:05.745Z,1259551145.745 [Startup:StartupSatComms:A](INFO): Timed out from 2009-11-30T03:18:05.5Z
2009-11-30T03:19:05.745Z,1259551145.745 [Startup:StartupSatComms:A] Stopped
2009-11-30T03:19:05.745Z,1259551145.745 [Startup:StartupSatComms:B] Running Loop=1
2009-11-30T03:19:06.146Z,1259551146.146 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2009-11-30T03:19:13.108Z,1259551153.108 [DataOverHttps](INFO): Sending 315 bytes from file Logs/20091130T000307/Courier0000.lzma
2009-11-30T03:19:14.110Z,1259551154.110 [DataOverHttps](INFO): Moved sent file to Logs/20091130T000307/Courier0000.lzma.bak
2009-11-30T03:19:14.110Z,1259551154.110 [DataOverHttps](INFO): SBD MOMSN=16648485
2009-11-30T03:19:25.135Z,1259551165.135 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2009-11-30T03:19:25.135Z,1259551165.135 [CTD_Seabird] No Fault, FailCount= 3
2009-11-30T03:19:25.220Z,1259551165.220 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2009-11-30T03:19:29.380Z,1259551169.380 [DataOverHttps](INFO): Sending 333 bytes from file Logs/20091130T031601/Courier0000.lzma
2009-11-30T03:19:30.382Z,1259551170.382 [DataOverHttps](INFO): Moved sent file to Logs/20091130T031601/Courier0000.lzma.bak
2009-11-30T03:19:30.382Z,1259551170.382 [DataOverHttps](INFO): SBD MOMSN=16648497
2009-11-30T03:19:38.272Z,1259551178.272 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2009-11-30T03:19:38.272Z,1259551178.272 [CTD_Seabird] Communications Fault, FailCount= 1
2009-11-30T03:19:38.272Z,1259551178.272 [CTD_Seabird](ERROR): Communications Fault
2009-11-30T03:19:38.515Z,1259551178.515 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2009-11-30T03:19:38.676Z,1259551178.676 [CTD_Seabird](INFO): Powering down
2009-11-30T03:19:39.666Z,1259551179.666 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2009-11-30T03:19:39.666Z,1259551179.666 [CTD_Seabird] No Fault, FailCount= 1
2009-11-30T03:19:40.020Z,1259551180.020 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2009-11-30T03:19:47.673Z,1259551187.673 [DataOverHttps](INFO): Sending 891 bytes from file Logs/20091130T000307/Express0001.lzma
2009-11-30T03:19:48.674Z,1259551188.674 [DataOverHttps](INFO): Moved sent file to Logs/20091130T000307/Express0001.lzma.bak
2009-11-30T03:19:48.674Z,1259551188.674 [DataOverHttps](INFO): SBD MOMSN=16648569
2009-11-30T03:19:53.072Z,1259551193.072 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2009-11-30T03:19:53.072Z,1259551193.072 [CTD_Seabird] Communications Fault, FailCount= 2
2009-11-30T03:19:53.072Z,1259551193.072 [CTD_Seabird](ERROR): Communications Fault
2009-11-30T03:19:53.425Z,1259551193.425 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2009-11-30T03:19:53.476Z,1259551193.476 [CTD_Seabird](INFO): Powering down
2009-11-30T03:19:54.641Z,1259551194.641 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2009-11-30T03:19:54.641Z,1259551194.641 [CTD_Seabird] No Fault, FailCount= 2
2009-11-30T03:19:54.816Z,1259551194.816 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2009-11-30T03:20:05.925Z,1259551205.925 [Startup:StartupSatComms:B](INFO): Timed out from 2009-11-30T03:19:05.7Z
2009-11-30T03:20:05.925Z,1259551205.925 [Startup:StartupSatComms:B] Stopped
2009-11-30T03:20:05.925Z,1259551205.925 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2009-11-30T03:20:05.925Z,1259551205.925 [Startup:StartupSatComms] Stopped
2009-11-30T03:20:05.925Z,1259551205.925 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2009-11-30T03:20:05.926Z,1259551205.926 [Startup](INFO): Completed Startup
2009-11-30T03:20:05.926Z,1259551205.926 [MissionManager](INFO): Startup is completed.
2009-11-30T03:20:05.926Z,1259551205.926 [MissionManager](INFO): Uninitializing Mission Startup
2009-11-30T03:20:05.926Z,1259551205.926 [Startup] Stopped
2009-11-30T03:20:05.926Z,1259551205.926 [Startup](DEBUG): Aggregate::uninitialize Startup
2009-11-30T03:20:05.926Z,1259551205.926 [Startup:A.GoToSurface] Stopped
2009-11-30T03:20:05.926Z,1259551205.926 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2009-11-30T03:20:06.337Z,1259551206.337 [MissionManager](IMPORTANT): Started mission Default
2009-11-30T03:20:06.338Z,1259551206.338 [Default] Running Loop=1
2009-11-30T03:20:06.338Z,1259551206.338 [Default](DEBUG): Aggregate::initialize Default
2009-11-30T03:20:06.338Z,1259551206.338 [Default:B.GoToSurface] Running Loop=1
2009-11-30T03:20:06.338Z,1259551206.338 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2009-11-30T03:20:06.338Z,1259551206.338 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2009-11-30T03:20:06.338Z,1259551206.338 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2009-11-30T03:20:06.339Z,1259551206.339 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2009-11-30T03:20:06.339Z,1259551206.339 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2009-11-30T03:20:06.339Z,1259551206.339 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2009-11-30T03:20:06.340Z,1259551206.340 [Default:A.Wait] Running Loop=1
2009-11-30T03:20:06.340Z,1259551206.340 [Default:A.Wait](DEBUG): Initialize Wait Component.
2009-11-30T03:20:06.437Z,1259551206.437 [DataOverHttps](INFO): Sending 1055 bytes from file Logs/20091130T031601/Express0001.lzma
2009-11-30T03:20:07.437Z,1259551207.437 [DataOverHttps](INFO): Moved sent file to Logs/20091130T031601/Express0001.lzma.bak
2009-11-30T03:20:07.438Z,1259551207.438 [DataOverHttps](INFO): SBD MOMSN=16648672
2009-11-30T03:20:07.872Z,1259551207.872 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2009-11-30T03:20:07.872Z,1259551207.872 [CTD_Seabird] Communications Fault, FailCount= 3
2009-11-30T03:20:07.872Z,1259551207.872 [CTD_Seabird](ERROR): Communications Fault
2009-11-30T03:20:07.953Z,1259551207.953 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2009-11-30T03:20:07.953Z,1259551207.953 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2009-11-30T03:20:08.276Z,1259551208.276 [CTD_Seabird](INFO): Powering down
2009-11-30T03:20:12.807Z,1259551212.807 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2009-11-30T03:20:12.808Z,1259551212.808 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2009-11-30T03:20:12.812Z,1259551212.812 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2009-11-30T03:20:12.838Z,1259551212.838 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2009-11-30T03:20:13.224Z,1259551213.224 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2009-11-30T03:20:13.224Z,1259551213.224 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2009-11-30T03:20:19.200Z,1259551219.200 [CommandExec](IMPORTANT): got command report mod SpeedControl.speedCmd
2009-11-30T03:20:19.277Z,1259551219.277 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2009-11-30T03:20:19.666Z,1259551219.666 [Default:A.Wait](INFO): Done Waiting.
2009-11-30T03:20:19.666Z,1259551219.666 [Default:A.Wait] Stopped
2009-11-30T03:20:19.666Z,1259551219.666 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2009-11-30T03:20:20.077Z,1259551220.077 [Default:CheckIn] Running Loop=1
2009-11-30T03:20:20.078Z,1259551220.078 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2009-11-30T03:20:20.078Z,1259551220.078 [Default:CheckIn:Read_GPS] Running Loop=1
2009-11-30T03:20:20.482Z,1259551220.482 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2009-11-30T03:20:50.730Z,1259551250.730 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/joystick_backseat.xml
2009-11-30T03:20:50.730Z,1259551250.730 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/joystick_backseat.xml
2009-11-30T03:20:50.890Z,1259551250.890 [MissionManager](INFO): DefineArg joystick_backseat.MissionTimeout = 2.000000 h
2009-11-30T03:20:50.896Z,1259551250.896 [MissionManager](INFO): DefineArg joystick_backseat.SpeedUpdateTimeout = 15.000000 s
2009-11-30T03:20:50.904Z,1259551250.904 [MissionManager](INFO): DefineArg joystick_backseat.HorizontalCommandMode = 0.000000 count
2009-11-30T03:20:50.907Z,1259551250.907 [MissionManager](INFO): DefineArg joystick_backseat.ElevatorAngle = -12.000000 arcdeg
2009-11-30T03:20:50.910Z,1259551250.910 [MissionManager](INFO): DefineOutput joystick_backseat.Speed = 0.000000 m/s
2009-11-30T03:20:50.913Z,1259551250.913 [MissionManager](INFO): DefineOutput joystick_backseat.Heading = 0.000000 arcdeg
2009-11-30T03:20:50.916Z,1259551250.916 [MissionManager](INFO): DefineOutput joystick_backseat.RudderAngle = 0.000000 arcdeg
2009-11-30T03:20:50.919Z,1259551250.919 [MissionManager](INFO): DefineOutput joystick_backseat.MassPosition = 0.990000 cm
2009-11-30T03:20:50.922Z,1259551250.922 [MissionManager](INFO): DefineOutput joystick_backseat.HeadingMode = 0.000000 count
2009-11-30T03:20:50.925Z,1259551250.925 [MissionManager](INFO): DefineOutput joystick_backseat.RudderMode = 1.000000 count
2009-11-30T03:20:50.971Z,1259551250.971 [MissionManager](INFO): DefineOutput joystick_backseat.ElapsedSinceMissionStarted = 0.000000 min
2009-11-30T03:20:51.469Z,1259551251.469 [joystick_backseat:H.BackseatDriver](DEBUG): Construct BackseatDriver.
2009-11-30T03:20:51.479Z,1259551251.479 [joystick_backseat:Surface:A.GoToSurface](DEBUG): Construct GoToSurface.
2009-11-30T03:20:51.488Z,1259551251.488 [joystick_backseat:JoystickControl:A.Buoyancy](DEBUG): Construct Buoyancy.
2009-11-30T03:20:51.492Z,1259551251.492 [joystick_backseat:JoystickControl:B.SetSpeed](DEBUG): Construct.
2009-11-30T03:20:51.495Z,1259551251.495 [joystick_backseat:JoystickControl:C.Mass](DEBUG): Construct.
2009-11-30T03:20:51.498Z,1259551251.498 [joystick_backseat:JoystickControl:D.Pitch](DEBUG): Construct.
2009-11-30T03:20:51.505Z,1259551251.505 [joystick_backseat:JoystickControl:CmdHeading:A.Point](DEBUG): Construct.
2009-11-30T03:20:51.513Z,1259551251.513 [joystick_backseat:JoystickControl:CmdRudder:A.Point](DEBUG): Construct.
2009-11-30T03:20:51.542Z,1259551251.542 [joystick_backseat:JoystickControl:CheckIn:C.Wait](DEBUG): Construct Wait.
2009-11-30T03:20:51.614Z,1259551251.614 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       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://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="joystick_backseat">

    <Description>
        This mission drives the vehicle on the surface at a speed
        and heading as commanded by a beackseat computer.

        The mission monitors the time between speed updates and
        stops the vehicle if SpeedUpdateTimeout expires.
    </Description>

<!-- Mission-wide settings -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>2</Value></DefineArg>

    <DefineArg Name="SpeedUpdateTimeout"><Description>
        Max time allowed between speed command updates.
    </Description><Units:second/><Value>15</Value></DefineArg>

    <DefineArg Name="HorizontalCommandMode"><Description>
        Mission horizontal command mode. 0 = Heading command (default). 1 = Rudder angle command.
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="ElevatorAngle"><Description>
        Holds elevator angle to keep vehicle pitched up on the surface.
    </Description><Units:degree/><Value>-12</Value></DefineArg>

<!-- Mission args do not change these. -->

    <DefineOutput Name="Speed"><Description>
        Commanded vehicle speed.
    </Description><Units:meter_per_second/><Value>0</Value></DefineOutput>

    <DefineOutput Name="Heading"><Description>
        Commanded vehicle heading. Mission will init Heading to current vehicle heading.
    </Description><Units:degree/><Value>0</Value></DefineOutput>

    <DefineOutput Name="RudderAngle"><Description>
        Commanded vehicle RudderAngle. Only acitve when mission is in rudder commands mode.
    </Description><Units:degree/><Value>0</Value></DefineOutput>

    <DefineOutput Name="MassPosition"><Description>
        Commanded vehicle mass position. Mission will init MassPosition to current vehicle default.
    </Description><Control:VerticalControl.massDefault/></DefineOutput>

    <DefineOutput Name="HeadingMode"><Description>
        Heading angle command mode enumaration.
    </Description><Units:count/><Value>0</Value></DefineOutput>

    <DefineOutput Name="RudderMode"><Description>
        Rudder angle command mode enumaration.
    </Description><Units:count/><Value>1</Value></DefineOutput>

    <DefineOutput Name="ElapsedSinceMissionStarted"><Units:minute/><Value>0</Value></DefineOutput>


<!-- Missions should always start with a timeout. -->

    <Timeout Duration="MissionTimeout"/>

<!-- Init Heading at current vehicle heading. -->

    <Assign><Sequence/><Arg Name="Heading"/><Universal:platform_orientation/></Assign>

<!-- Add guidance update directives high in the stack. -->

    <Aggregate Id="SpeedTimeout">

        <When>
            <Elapsed><Arg Name="Speed"/></Elapsed>
            <Ge><Arg Name="SpeedUpdateTimeout"/></Ge>
        </When>

        <Assign><Sequence/><Arg Name="Speed"/><Units:meter_per_second/><Value>0</Value></Assign>

        <Syslog Severity="Fault">Speed timeout reached. Stopping vehicle (speed <Arg Name="Speed"/><Units:meter_per_second/>).</Syslog>

    </Aggregate>

    <Aggregate Id="UpdateSpeed">

        <When>
            <Elapsed><CustomUri Uri="_.speedCmd"/></Elapsed>
            <Lt><Elapsed><Arg Name="Speed"/></Elapsed></Lt>
        </When>

        <Assign><Sequence/><Arg Name="Speed"/><CustomUri Uri="_.speedCmd"/></Assign>

        <Syslog Severity="Info">Updating vehicle speed <Arg Name="Speed"/><Units:meter_per_second/></Syslog>

    </Aggregate>

    <Aggregate Id="UpdateCommandMode">

        <When>
            <Elapsed><CustomUri Uri="_.horizontalCmdMode"/></Elapsed>
            <Lt><Elapsed><Arg Name="HorizontalCommandMode"/></Elapsed></Lt>
            <And>
                <CustomUri Uri="_.horizontalCmdMode"/><Eq><Arg Name="HeadingMode"/></Eq>
                <Or><CustomUri Uri="_.horizontalCmdMode"/><Eq><Arg Name="RudderMode"/></Eq></Or>
            </And>
        </When>

        <Assign><Sequence/><Arg Name="HorizontalCommandMode"/><CustomUri Uri="_.horizontalCmdMode"/></Assign>

        <Syslog Severity="Info">Updating horizontal command mode <Arg Name="HorizontalCommandMode"/><Units:count/></Syslog>

    </Aggregate>

    <Aggregate Id="UpdateHeading">

        <When>
            <Elapsed><CustomUri Uri="_.headingCmd"/></Elapsed>
            <Lt><Elapsed><Arg Name="Heading"/></Elapsed></Lt>
        </When>

        <Assign><Sequence/><Arg Name="Heading"/><CustomUri Uri="_.headingCmd"/></Assign>

        <Syslog Severity="Info">Updating heading <Arg Name="Heading"/><Units:degree/></Syslog>

    </Aggregate>

    <Aggregate Id="UpdateRudder">

        <When>
            <Elapsed><CustomUri Uri="_.rudderAngleCmd"/></Elapsed>
            <Lt><Elapsed><Arg Name="RudderAngle"/></Elapsed></Lt>
        </When>

        <Assign><Sequence/><Arg Name="RudderAngle"/><CustomUri Uri="_.rudderAngleCmd"/></Assign>

        <Syslog Severity="Info">Updating rudder angle <Arg Name="RudderAngle"/><Units:degree/></Syslog>

    </Aggregate>

    <Aggregate Id="UpdateMassPosition">

        <When>
            <Elapsed><CustomUri Uri="_.massPositionCmd"/></Elapsed>
            <Lt><Elapsed><Arg Name="MassPosition"/></Elapsed></Lt>
        </When>

        <Assign><Sequence/><Arg Name="MassPosition"/><CustomUri Uri="_.massPositionCmd"/></Assign>

        <Syslog Severity="Info">Updating mass position <Arg Name="MassPosition"/><Units:millimeter/></Syslog>

    </Aggregate>

<!-- Power the Backseat payload. -->

    <Guidance:BackseatDriver>
        <Parallel/>
    </Guidance:BackseatDriver>

<!-- Keep the vehilce on the surface. -->

    <Aggregate Id="Surface">
        <While>
            <Universal:depth/><Ge><Control:VerticalControl.surfaceThreshold/></Ge>
        </While>

        <Guidance:GoToSurface>
            <Progression/>
        </Guidance:GoToSurface>
    </Aggregate>

<!-- Start mission sequence. -->

    <Aggregate Id="JoystickControl">

        <Sequence/>

        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyDefault/></Setting>
        </Guidance:Buoyancy>

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

        <Guidance:Mass>
            <Parallel/>
            <Setting><Guidance:Mass.position/><Arg Name="MassPosition"/></Setting>
        </Guidance:Mass>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.elevatorAngle/><Arg Name="ElevatorAngle"/></Setting>
        </Guidance:Pitch>

        <Aggregate Id="CmdHeading">

            <While>
                <Arg Name="HorizontalCommandMode"/><Eq><Arg Name="HeadingMode"/></Eq>
            </While>

            <Guidance:Point>
                <Parallel/>
                <Setting><Guidance:Point.forceUpdate/><True/></Setting>
                <Setting><Guidance:Point.heading/><Arg Name="Heading"/></Setting>
            </Guidance:Point>

        </Aggregate>

        <Aggregate Id="CmdRudder">

            <While>
                <Arg Name="HorizontalCommandMode"/><Eq><Arg Name="RudderMode"/></Eq>
            </While>

            <Guidance:Point>
                <Parallel/>
                <Setting><Guidance:Point.forceUpdate/><True/></Setting>
                <Setting><Guidance:Point.rudderAngle/><Arg Name="RudderAngle"/></Setting>
            </Guidance:Point>

        </Aggregate>

        <Aggregate Id="CheckIn">

            <Sequence Repeat="288"/>

            <ReadDatum Id="Read_GPS">
                <Timeout Duration="P5M"/>
                <Universal:time_fix/>
            </ReadDatum>

            <ReadDatum Id="Read_Iridium">
                <Universal:platform_communications/>
            </ReadDatum>

            <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
            </Guidance:Wait>

            <Assign><Sequence/><Arg Name="ElapsedSinceMissionStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

            <Syslog Severity="Important">Joystick mission has been running for <Arg Name="ElapsedSinceMissionStarted"/><Units:minute/></Syslog>

        </Aggregate>

    </Aggregate>

</Mission>


2009-11-30T03:20:51.623Z,1259551251.623 [CommandExec](IMPORTANT): Running ./Missions/Engineering/joystick_backseat.xml
2009-11-30T03:20:51.999Z,1259551251.999 [Default] Stopped
2009-11-30T03:20:51.000Z,1259551252.000 [Default](DEBUG): Aggregate::uninitialize Default
2009-11-30T03:20:51.000Z,1259551252.000 [Default:B.GoToSurface] Stopped
2009-11-30T03:20:51.000Z,1259551252.000 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2009-11-30T03:20:52.000Z,1259551252.000 [Default:CheckIn] Stopped
2009-11-30T03:20:52.000Z,1259551252.000 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2009-11-30T03:20:52.000Z,1259551252.000 [Default:CheckIn:Read_GPS] Stopped
2009-11-30T03:20:52.000Z,1259551252.000 [MissionManager](IMPORTANT): Started mission joystick_backseat
2009-11-30T03:20:52.001Z,1259551252.001 [joystick_backseat] Running Loop=1
2009-11-30T03:20:52.001Z,1259551252.001 [joystick_backseat](DEBUG): Aggregate::initialize joystick_backseat
2009-11-30T03:20:52.001Z,1259551252.001 [joystick_backseat:H.BackseatDriver] Running Loop=1
2009-11-30T03:20:52.001Z,1259551252.001 [joystick_backseat:H.BackseatDriver](DEBUG): Initialize.
2009-11-30T03:20:52.002Z,1259551252.002 [joystick_backseat:H.BackseatDriver](ERROR): Failed to authorize unknown component BackseatComponent
2009-11-30T03:20:52.090Z,1259551252.090 [joystick_backseat:Surface] Running Loop=1
2009-11-30T03:20:52.091Z,1259551252.091 [joystick_backseat:Surface](DEBUG): Aggregate::initialize joystick_backseat:Surface
2009-11-30T03:20:52.091Z,1259551252.091 [joystick_backseat:Surface:A.GoToSurface] Running Loop=1
2009-11-30T03:20:52.091Z,1259551252.091 [joystick_backseat:Surface:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2009-11-30T03:20:52.091Z,1259551252.091 [joystick_backseat:Surface:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2009-11-30T03:20:52.091Z,1259551252.091 [joystick_backseat:Surface:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2009-11-30T03:20:52.100Z,1259551252.100 [joystick_backseat:Surface:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2009-11-30T03:20:52.101Z,1259551252.101 [joystick_backseat:Surface:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2009-11-30T03:20:52.101Z,1259551252.101 [joystick_backseat:Surface:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2009-11-30T03:20:52.102Z,1259551252.102 [joystick_backseat:A] Running Loop=1
2009-11-30T03:20:52.102Z,1259551252.102 [joystick_backseat:Surface] Stopped
2009-11-30T03:20:52.102Z,1259551252.102 [joystick_backseat:Surface](DEBUG): Aggregate::uninitialize joystick_backseat:Surface
2009-11-30T03:20:52.103Z,1259551252.103 [joystick_backseat:Surface:A.GoToSurface] Stopped
2009-11-30T03:20:52.103Z,1259551252.103 [joystick_backseat:Surface:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2009-11-30T03:20:52.103Z,1259551252.103 [joystick_backseat:H.BackseatDriver] Running Loop=1
2009-11-30T03:20:52.104Z,1259551252.104 [joystick_backseat:A] Stopped
2009-11-30T03:20:52.104Z,1259551252.104 [joystick_backseat:JoystickControl] Running Loop=1
2009-11-30T03:20:52.104Z,1259551252.104 [joystick_backseat:JoystickControl](DEBUG): Aggregate::initialize joystick_backseat:JoystickControl
2009-11-30T03:20:52.104Z,1259551252.104 [joystick_backseat:JoystickControl:A.Buoyancy] Running Loop=1
2009-11-30T03:20:52.105Z,1259551252.105 [joystick_backseat:JoystickControl:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2009-11-30T03:20:52.105Z,1259551252.105 [joystick_backseat:JoystickControl:B.SetSpeed] Running Loop=1
2009-11-30T03:20:52.105Z,1259551252.105 [joystick_backseat:JoystickControl:B.SetSpeed](DEBUG): Initialize.
2009-11-30T03:20:52.105Z,1259551252.105 [joystick_backseat:JoystickControl:C.Mass] Running Loop=1
2009-11-30T03:20:52.105Z,1259551252.105 [joystick_backseat:JoystickControl:C.Mass](DEBUG): Initialize.
2009-11-30T03:20:52.105Z,1259551252.105 [joystick_backseat:JoystickControl:D.Pitch] Running Loop=1
2009-11-30T03:20:52.105Z,1259551252.105 [joystick_backseat:JoystickControl:D.Pitch](DEBUG): Initialize.
2009-11-30T03:20:52.106Z,1259551252.106 [joystick_backseat:JoystickControl:CmdHeading] Running Loop=1
2009-11-30T03:20:52.106Z,1259551252.106 [joystick_backseat:JoystickControl:CmdHeading](DEBUG): Aggregate::initialize joystick_backseat:JoystickControl:CmdHeading
2009-11-30T03:20:52.106Z,1259551252.106 [joystick_backseat:JoystickControl:CmdHeading:A.Point] Running Loop=1
2009-11-30T03:20:52.106Z,1259551252.106 [joystick_backseat:JoystickControl:CmdHeading:A.Point](DEBUG): Initialize.
2009-11-30T03:20:52.106Z,1259551252.106 [joystick_backseat:JoystickControl:CmdRudder] Running Loop=1
2009-11-30T03:20:52.106Z,1259551252.106 [joystick_backseat:JoystickControl:CmdRudder](DEBUG): Aggregate::initialize joystick_backseat:JoystickControl:CmdRudder
2009-11-30T03:20:52.106Z,1259551252.106 [joystick_backseat:JoystickControl:CmdRudder:A.Point] Running Loop=1
2009-11-30T03:20:52.106Z,1259551252.106 [joystick_backseat:JoystickControl:CmdRudder:A.Point](DEBUG): Initialize.
2009-11-30T03:20:52.107Z,1259551252.107 [joystick_backseat:JoystickControl:CheckIn] Running Loop=1
2009-11-30T03:20:52.107Z,1259551252.107 [joystick_backseat:JoystickControl:CheckIn](DEBUG): Aggregate::initialize joystick_backseat:JoystickControl:CheckIn
2009-11-30T03:20:52.107Z,1259551252.107 [joystick_backseat:JoystickControl:CheckIn:Read_GPS] Running Loop=1
2009-11-30T03:20:52.381Z,1259551252.381 [joystick_backseat:JoystickControl:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2009-11-30T03:20:52.381Z,1259551252.381 [joystick_backseat:JoystickControl:CmdRudder] Stopped
2009-11-30T03:20:52.381Z,1259551252.381 [joystick_backseat:JoystickControl:CmdRudder](DEBUG): Aggregate::uninitialize joystick_backseat:JoystickControl:CmdRudder
2009-11-30T03:20:52.381Z,1259551252.381 [joystick_backseat:JoystickControl:CmdRudder:A.Point] Stopped
2009-11-30T03:20:52.381Z,1259551252.381 [joystick_backseat:JoystickControl:CmdHeading] Running Loop=1
2009-11-30T03:20:52.381Z,1259551252.381 [joystick_backseat:JoystickControl:CmdHeading:A.Point] Running Loop=1
2009-11-30T03:20:52.382Z,1259551252.382 [joystick_backseat:JoystickControl:D.Pitch] Running Loop=1
2009-11-30T03:20:52.383Z,1259551252.383 [joystick_backseat:JoystickControl:C.Mass] Running Loop=1
2009-11-30T03:20:52.383Z,1259551252.383 [joystick_backseat:JoystickControl:B.SetSpeed] Running Loop=1
2009-11-30T03:20:52.383Z,1259551252.383 [joystick_backseat:JoystickControl:A.Buoyancy] Running Loop=1
2009-11-30T03:20:52.799Z,1259551252.799 [joystick_backseat:H.BackseatDriver](INFO): Created data writer for _.headingCmd with code: 1741
2009-11-30T03:20:52.801Z,1259551252.801 [joystick_backseat:H.BackseatDriver](INFO): Created data writer for _.horizontalCmdMode with code: 1740
2009-11-30T03:20:52.803Z,1259551252.803 [joystick_backseat:H.BackseatDriver](INFO): Created data writer for _.speedCmd with code: 1739
2009-11-30T03:20:53.208Z,1259551253.208 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:53.208Z,1259551253.208 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:53.208Z,1259551253.208 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:53.208Z,1259551253.208 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:53.208Z,1259551253.208 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:53.209Z,1259551253.209 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:53.209Z,1259551253.209 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:53.209Z,1259551253.209 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:53.209Z,1259551253.209 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:53.209Z,1259551253.209 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:53.209Z,1259551253.209 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:53.209Z,1259551253.209 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:53.209Z,1259551253.209 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:53.210Z,1259551253.210 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:53.210Z,1259551253.210 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:20:53.596Z,1259551253.596 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:20:53.596Z,1259551253.596 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:20:53.597Z,1259551253.597 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:20:53.597Z,1259551253.597 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:20:53.597Z,1259551253.597 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:20:53.597Z,1259551253.597 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:20:53.597Z,1259551253.597 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:20:53.597Z,1259551253.597 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:20:53.597Z,1259551253.597 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:20:53.598Z,1259551253.598 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:53.598Z,1259551253.598 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:20:53.598Z,1259551253.598 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:20:53.598Z,1259551253.598 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:20:53.598Z,1259551253.598 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:20:53.598Z,1259551253.598 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:54.008Z,1259551254.008 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:54.008Z,1259551254.008 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:54.008Z,1259551254.008 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:54.008Z,1259551254.008 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:54.008Z,1259551254.008 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:54.009Z,1259551254.009 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:54.009Z,1259551254.009 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:54.009Z,1259551254.009 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:54.009Z,1259551254.009 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:54.009Z,1259551254.009 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:54.009Z,1259551254.009 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:54.009Z,1259551254.009 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:54.009Z,1259551254.009 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:54.010Z,1259551254.010 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:54.010Z,1259551254.010 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:20:54.432Z,1259551254.432 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:20:54.432Z,1259551254.432 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:20:54.433Z,1259551254.433 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:20:54.433Z,1259551254.433 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:20:54.433Z,1259551254.433 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:20:54.433Z,1259551254.433 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:20:54.433Z,1259551254.433 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:20:54.433Z,1259551254.433 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:20:54.433Z,1259551254.433 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:20:54.434Z,1259551254.434 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:54.434Z,1259551254.434 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:20:54.434Z,1259551254.434 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:20:54.434Z,1259551254.434 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:20:54.434Z,1259551254.434 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:20:54.434Z,1259551254.434 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:54.804Z,1259551254.804 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:54.804Z,1259551254.804 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:54.804Z,1259551254.804 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:54.804Z,1259551254.804 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:54.805Z,1259551254.805 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:54.806Z,1259551254.806 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:54.806Z,1259551254.806 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:20:55.220Z,1259551255.220 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:20:55.220Z,1259551255.220 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:20:55.221Z,1259551255.221 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:20:55.221Z,1259551255.221 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:20:55.221Z,1259551255.221 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:20:55.221Z,1259551255.221 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:20:55.221Z,1259551255.221 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:20:55.221Z,1259551255.221 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:20:55.221Z,1259551255.221 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:20:55.222Z,1259551255.222 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:55.222Z,1259551255.222 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:20:55.222Z,1259551255.222 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:20:55.222Z,1259551255.222 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:20:55.222Z,1259551255.222 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:20:55.222Z,1259551255.222 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:55.624Z,1259551255.624 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:55.624Z,1259551255.624 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:55.624Z,1259551255.624 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:55.624Z,1259551255.624 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:55.624Z,1259551255.624 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:55.625Z,1259551255.625 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:55.625Z,1259551255.625 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:55.625Z,1259551255.625 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:55.625Z,1259551255.625 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:55.625Z,1259551255.625 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:55.625Z,1259551255.625 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:55.625Z,1259551255.625 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:55.625Z,1259551255.625 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:55.626Z,1259551255.626 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:55.626Z,1259551255.626 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:20:56.044Z,1259551256.044 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:20:56.045Z,1259551256.045 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:20:56.045Z,1259551256.045 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:20:56.045Z,1259551256.045 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:20:56.045Z,1259551256.045 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:20:56.045Z,1259551256.045 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:20:56.045Z,1259551256.045 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:20:56.046Z,1259551256.046 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:20:56.046Z,1259551256.046 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:20:56.046Z,1259551256.046 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:56.046Z,1259551256.046 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:20:56.046Z,1259551256.046 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:20:56.046Z,1259551256.046 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:20:56.046Z,1259551256.046 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:20:56.047Z,1259551256.047 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:56.464Z,1259551256.464 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:56.464Z,1259551256.464 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:56.464Z,1259551256.464 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:56.464Z,1259551256.464 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:56.464Z,1259551256.464 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:56.465Z,1259551256.465 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:56.465Z,1259551256.465 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:56.465Z,1259551256.465 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:56.465Z,1259551256.465 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:56.465Z,1259551256.465 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:56.465Z,1259551256.465 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:56.465Z,1259551256.465 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:56.465Z,1259551256.465 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:56.466Z,1259551256.466 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:56.466Z,1259551256.466 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:20:56.852Z,1259551256.852 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:20:56.852Z,1259551256.852 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:20:56.853Z,1259551256.853 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:20:56.853Z,1259551256.853 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:20:56.853Z,1259551256.853 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:20:56.853Z,1259551256.853 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:20:56.853Z,1259551256.853 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:20:56.853Z,1259551256.853 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:20:56.853Z,1259551256.853 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:20:56.854Z,1259551256.854 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:56.854Z,1259551256.854 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:20:56.854Z,1259551256.854 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:20:56.854Z,1259551256.854 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:20:56.854Z,1259551256.854 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:20:56.854Z,1259551256.854 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:57.256Z,1259551257.256 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:57.256Z,1259551257.256 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:57.256Z,1259551257.256 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:57.256Z,1259551257.256 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:57.256Z,1259551257.256 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:57.257Z,1259551257.257 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:57.257Z,1259551257.257 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:57.257Z,1259551257.257 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:57.257Z,1259551257.257 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:57.257Z,1259551257.257 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:57.257Z,1259551257.257 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:57.257Z,1259551257.257 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:57.257Z,1259551257.257 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:57.258Z,1259551257.258 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:57.258Z,1259551257.258 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:20:57.644Z,1259551257.644 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:20:57.644Z,1259551257.644 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:20:57.644Z,1259551257.644 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:20:57.645Z,1259551257.645 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:20:57.645Z,1259551257.645 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:20:57.645Z,1259551257.645 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:20:57.645Z,1259551257.645 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:20:57.645Z,1259551257.645 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:20:57.645Z,1259551257.645 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:20:57.646Z,1259551257.646 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:57.646Z,1259551257.646 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:20:57.646Z,1259551257.646 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:20:57.646Z,1259551257.646 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:20:57.646Z,1259551257.646 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:20:57.646Z,1259551257.646 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:58.043Z,1259551258.043 [joystick_backseat:H.BackseatDriver](INFO): Created data writer for _.rudderAngleCmd with code: 1742
2009-11-30T03:20:58.044Z,1259551258.044 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:58.044Z,1259551258.044 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:58.044Z,1259551258.044 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:58.045Z,1259551258.045 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:58.045Z,1259551258.045 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:58.045Z,1259551258.045 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:58.045Z,1259551258.045 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:58.045Z,1259551258.045 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:58.045Z,1259551258.045 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:58.045Z,1259551258.045 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:58.045Z,1259551258.045 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:58.046Z,1259551258.046 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:58.046Z,1259551258.046 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:58.046Z,1259551258.046 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:58.046Z,1259551258.046 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:20:58.451Z,1259551258.451 [joystick_backseat:JoystickControl:CmdRudder] Running Loop=1
2009-11-30T03:20:58.452Z,1259551258.452 [joystick_backseat:JoystickControl:CmdRudder](DEBUG): Aggregate::initialize joystick_backseat:JoystickControl:CmdRudder
2009-11-30T03:20:58.452Z,1259551258.452 [joystick_backseat:JoystickControl:CmdRudder:A.Point] Running Loop=1
2009-11-30T03:20:58.452Z,1259551258.452 [joystick_backseat:JoystickControl:CmdRudder] Running Loop=1
2009-11-30T03:20:58.452Z,1259551258.452 [joystick_backseat:JoystickControl:CmdRudder:A.Point] Running Loop=1
2009-11-30T03:20:58.453Z,1259551258.453 [joystick_backseat:JoystickControl:CmdHeading] Stopped
2009-11-30T03:20:58.453Z,1259551258.453 [joystick_backseat:JoystickControl:CmdHeading](DEBUG): Aggregate::uninitialize joystick_backseat:JoystickControl:CmdHeading
2009-11-30T03:20:58.453Z,1259551258.453 [joystick_backseat:JoystickControl:CmdHeading:A.Point] Stopped
2009-11-30T03:20:58.460Z,1259551258.460 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:20:58.460Z,1259551258.460 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:20:58.460Z,1259551258.460 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:20:58.460Z,1259551258.460 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:20:58.461Z,1259551258.461 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:20:58.461Z,1259551258.461 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:20:58.461Z,1259551258.461 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:20:58.461Z,1259551258.461 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:20:58.461Z,1259551258.461 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:20:58.461Z,1259551258.461 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:20:58.462Z,1259551258.462 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 1.000000 count 
2009-11-30T03:20:58.462Z,1259551258.462 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:20:58.462Z,1259551258.462 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:20:58.462Z,1259551258.462 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:20:58.462Z,1259551258.462 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:58.463Z,1259551258.463 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:20:58.463Z,1259551258.463 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:20:58.463Z,1259551258.463 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:20:58.463Z,1259551258.463 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:20:58.463Z,1259551258.463 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:58.872Z,1259551258.872 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:20:58.872Z,1259551258.872 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:58.873Z,1259551258.873 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:58.874Z,1259551258.874 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:58.874Z,1259551258.874 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:58.874Z,1259551258.874 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:58.874Z,1259551258.874 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:58.874Z,1259551258.874 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:58.874Z,1259551258.874 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:58.874Z,1259551258.874 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:58.875Z,1259551258.875 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:58.875Z,1259551258.875 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:20:59.264Z,1259551259.264 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:20:59.264Z,1259551259.264 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:20:59.264Z,1259551259.264 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:20:59.264Z,1259551259.264 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:20:59.264Z,1259551259.264 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:20:59.265Z,1259551259.265 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:20:59.265Z,1259551259.265 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:20:59.265Z,1259551259.265 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:20:59.265Z,1259551259.265 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:20:59.265Z,1259551259.265 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:20:59.266Z,1259551259.266 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 1.000000 count 
2009-11-30T03:20:59.266Z,1259551259.266 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:20:59.266Z,1259551259.266 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:20:59.266Z,1259551259.266 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:20:59.266Z,1259551259.266 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:59.267Z,1259551259.267 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed -0.080000 m/s 
2009-11-30T03:20:59.267Z,1259551259.267 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:20:59.267Z,1259551259.267 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:20:59.267Z,1259551259.267 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:20:59.267Z,1259551259.267 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:59.313Z,1259551259.313 [Reporter](INFO): SpeedControl.speedCmd -0.080000 m/s
2009-11-30T03:20:59.668Z,1259551259.668 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:20:59.668Z,1259551259.668 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:20:59.669Z,1259551259.669 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:20:59.670Z,1259551259.670 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:20:59.670Z,1259551259.670 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:20:59.670Z,1259551259.670 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:20:59.670Z,1259551259.670 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:20:59.670Z,1259551259.670 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:20:59.670Z,1259551259.670 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:20:59.670Z,1259551259.670 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:20:59.671Z,1259551259.671 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:20:59.671Z,1259551259.671 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:00.084Z,1259551260.084 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:00.084Z,1259551260.084 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:00.084Z,1259551260.084 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:00.084Z,1259551260.084 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:00.084Z,1259551260.084 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:00.085Z,1259551260.085 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:00.085Z,1259551260.085 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:00.085Z,1259551260.085 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:00.085Z,1259551260.085 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:00.085Z,1259551260.085 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:00.086Z,1259551260.086 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 1.000000 count 
2009-11-30T03:21:00.086Z,1259551260.086 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:00.086Z,1259551260.086 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:00.086Z,1259551260.086 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:00.086Z,1259551260.086 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:00.087Z,1259551260.087 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 1.000000 m/s 
2009-11-30T03:21:00.087Z,1259551260.087 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:00.087Z,1259551260.087 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:00.087Z,1259551260.087 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:00.087Z,1259551260.087 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:00.096Z,1259551260.096 [Reporter](INFO): SpeedControl.speedCmd 1.000000 m/s
2009-11-30T03:21:00.472Z,1259551260.472 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:00.472Z,1259551260.472 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:00.473Z,1259551260.473 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:00.474Z,1259551260.474 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:00.474Z,1259551260.474 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:00.474Z,1259551260.474 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:00.474Z,1259551260.474 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:00.474Z,1259551260.474 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:00.474Z,1259551260.474 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:00.474Z,1259551260.474 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:00.475Z,1259551260.475 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:00.475Z,1259551260.475 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:00.880Z,1259551260.880 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:00.880Z,1259551260.880 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:00.880Z,1259551260.880 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:00.880Z,1259551260.880 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:00.880Z,1259551260.880 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:00.881Z,1259551260.881 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:00.881Z,1259551260.881 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:00.881Z,1259551260.881 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:00.881Z,1259551260.881 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:00.881Z,1259551260.881 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:00.882Z,1259551260.882 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 1.000000 count 
2009-11-30T03:21:00.882Z,1259551260.882 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:00.882Z,1259551260.882 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:00.882Z,1259551260.882 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:00.882Z,1259551260.882 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:00.883Z,1259551260.883 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 1.000000 m/s 
2009-11-30T03:21:00.883Z,1259551260.883 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:00.883Z,1259551260.883 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:00.883Z,1259551260.883 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:00.883Z,1259551260.883 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:01.308Z,1259551261.308 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:01.308Z,1259551261.308 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:01.309Z,1259551261.309 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:01.310Z,1259551261.310 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:01.310Z,1259551261.310 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:01.310Z,1259551261.310 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:01.310Z,1259551261.310 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:01.310Z,1259551261.310 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:01.310Z,1259551261.310 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:01.310Z,1259551261.310 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:01.311Z,1259551261.311 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:01.311Z,1259551261.311 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:01.732Z,1259551261.732 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:01.732Z,1259551261.732 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:01.732Z,1259551261.732 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:01.732Z,1259551261.732 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:01.733Z,1259551261.733 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:01.733Z,1259551261.733 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:01.733Z,1259551261.733 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:01.733Z,1259551261.733 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:01.733Z,1259551261.733 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:01.733Z,1259551261.733 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:01.734Z,1259551261.734 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 1.000000 count 
2009-11-30T03:21:01.734Z,1259551261.734 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:01.734Z,1259551261.734 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:01.734Z,1259551261.734 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:01.734Z,1259551261.734 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:01.735Z,1259551261.735 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 1.000000 m/s 
2009-11-30T03:21:01.735Z,1259551261.735 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:01.735Z,1259551261.735 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:01.735Z,1259551261.735 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:01.735Z,1259551261.735 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:02.112Z,1259551262.112 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:02.112Z,1259551262.112 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:02.113Z,1259551262.113 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:02.114Z,1259551262.114 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:02.114Z,1259551262.114 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:02.114Z,1259551262.114 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:02.114Z,1259551262.114 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:02.114Z,1259551262.114 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:02.114Z,1259551262.114 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:02.114Z,1259551262.114 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:02.115Z,1259551262.115 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:02.115Z,1259551262.115 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:02.510Z,1259551262.510 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:02.510Z,1259551262.510 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:02.510Z,1259551262.510 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:02.510Z,1259551262.510 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:02.511Z,1259551262.511 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:02.511Z,1259551262.511 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:02.511Z,1259551262.511 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:02.511Z,1259551262.511 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:02.511Z,1259551262.511 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:02.511Z,1259551262.511 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:02.512Z,1259551262.512 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 1.000000 count 
2009-11-30T03:21:02.512Z,1259551262.512 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:02.512Z,1259551262.512 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:02.512Z,1259551262.512 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:02.513Z,1259551262.513 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:02.513Z,1259551262.513 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:21:02.513Z,1259551262.513 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:02.513Z,1259551262.513 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:02.513Z,1259551262.513 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:02.513Z,1259551262.513 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:02.553Z,1259551262.553 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2009-11-30T03:21:02.892Z,1259551262.892 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:02.892Z,1259551262.892 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:02.893Z,1259551262.893 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:02.894Z,1259551262.894 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:02.894Z,1259551262.894 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:02.894Z,1259551262.894 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:02.894Z,1259551262.894 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:02.894Z,1259551262.894 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:02.894Z,1259551262.894 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:02.894Z,1259551262.894 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:02.895Z,1259551262.895 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:02.895Z,1259551262.895 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:03.304Z,1259551263.304 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:03.304Z,1259551263.304 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:03.304Z,1259551263.304 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:03.304Z,1259551263.304 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:03.304Z,1259551263.304 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:03.305Z,1259551263.305 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:03.305Z,1259551263.305 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:03.305Z,1259551263.305 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:03.305Z,1259551263.305 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:03.305Z,1259551263.305 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:03.306Z,1259551263.306 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 1.000000 count 
2009-11-30T03:21:03.306Z,1259551263.306 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:03.306Z,1259551263.306 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:03.306Z,1259551263.306 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:03.306Z,1259551263.306 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:03.307Z,1259551263.307 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed -0.050000 m/s 
2009-11-30T03:21:03.307Z,1259551263.307 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:03.307Z,1259551263.307 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:03.307Z,1259551263.307 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:03.307Z,1259551263.307 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:03.318Z,1259551263.318 [Reporter](INFO): SpeedControl.speedCmd -0.050000 m/s
2009-11-30T03:21:03.716Z,1259551263.716 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:03.716Z,1259551263.716 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:03.717Z,1259551263.717 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:03.718Z,1259551263.718 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:03.718Z,1259551263.718 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:03.718Z,1259551263.718 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:03.718Z,1259551263.718 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:03.718Z,1259551263.718 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:03.718Z,1259551263.718 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:03.718Z,1259551263.718 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:03.719Z,1259551263.719 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:03.719Z,1259551263.719 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:04.104Z,1259551264.104 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:04.104Z,1259551264.104 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:04.104Z,1259551264.104 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:04.104Z,1259551264.104 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:04.104Z,1259551264.104 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:04.105Z,1259551264.105 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:04.105Z,1259551264.105 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:04.105Z,1259551264.105 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:04.105Z,1259551264.105 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:04.105Z,1259551264.105 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:04.106Z,1259551264.106 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 1.000000 count 
2009-11-30T03:21:04.106Z,1259551264.106 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:04.106Z,1259551264.106 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:04.106Z,1259551264.106 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:04.106Z,1259551264.106 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:04.107Z,1259551264.107 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed -0.050000 m/s 
2009-11-30T03:21:04.107Z,1259551264.107 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:04.107Z,1259551264.107 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:04.107Z,1259551264.107 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:04.107Z,1259551264.107 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:04.512Z,1259551264.512 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:04.512Z,1259551264.512 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:04.513Z,1259551264.513 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:04.514Z,1259551264.514 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:04.514Z,1259551264.514 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:04.514Z,1259551264.514 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:04.514Z,1259551264.514 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:04.514Z,1259551264.514 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:04.514Z,1259551264.514 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:04.514Z,1259551264.514 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:04.515Z,1259551264.515 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:04.515Z,1259551264.515 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:04.921Z,1259551264.921 [joystick_backseat:JoystickControl:CmdRudder] Stopped
2009-11-30T03:21:04.921Z,1259551264.921 [joystick_backseat:JoystickControl:CmdRudder](DEBUG): Aggregate::uninitialize joystick_backseat:JoystickControl:CmdRudder
2009-11-30T03:21:04.921Z,1259551264.921 [joystick_backseat:JoystickControl:CmdRudder:A.Point] Stopped
2009-11-30T03:21:04.921Z,1259551264.921 [joystick_backseat:JoystickControl:CmdHeading] Running Loop=1
2009-11-30T03:21:04.921Z,1259551264.921 [joystick_backseat:JoystickControl:CmdHeading](DEBUG): Aggregate::initialize joystick_backseat:JoystickControl:CmdHeading
2009-11-30T03:21:04.921Z,1259551264.921 [joystick_backseat:JoystickControl:CmdHeading:A.Point] Running Loop=1
2009-11-30T03:21:04.928Z,1259551264.928 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:04.928Z,1259551264.928 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:04.928Z,1259551264.928 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:04.928Z,1259551264.928 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:04.928Z,1259551264.928 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:04.929Z,1259551264.929 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:04.929Z,1259551264.929 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:04.929Z,1259551264.929 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:04.929Z,1259551264.929 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:04.929Z,1259551264.929 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:04.930Z,1259551264.930 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:21:04.930Z,1259551264.930 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:04.930Z,1259551264.930 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:04.930Z,1259551264.930 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:04.930Z,1259551264.930 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:04.931Z,1259551264.931 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:21:04.931Z,1259551264.931 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:04.931Z,1259551264.931 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:04.931Z,1259551264.931 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:04.931Z,1259551264.931 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:04.941Z,1259551264.941 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2009-11-30T03:21:05.328Z,1259551265.328 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:05.328Z,1259551265.328 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:05.329Z,1259551265.329 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:05.330Z,1259551265.330 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:05.330Z,1259551265.330 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:05.330Z,1259551265.330 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:05.330Z,1259551265.330 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:05.330Z,1259551265.330 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:05.330Z,1259551265.330 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:05.330Z,1259551265.330 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:05.331Z,1259551265.331 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:05.331Z,1259551265.331 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:05.728Z,1259551265.728 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:05.728Z,1259551265.728 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:05.728Z,1259551265.728 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:05.729Z,1259551265.729 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:05.729Z,1259551265.729 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:05.729Z,1259551265.729 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:05.729Z,1259551265.729 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:05.729Z,1259551265.729 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:05.729Z,1259551265.729 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:05.730Z,1259551265.730 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:05.730Z,1259551265.730 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:21:05.730Z,1259551265.730 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:05.730Z,1259551265.730 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:05.730Z,1259551265.730 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:05.730Z,1259551265.730 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:05.731Z,1259551265.731 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:21:05.731Z,1259551265.731 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:05.731Z,1259551265.731 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:05.731Z,1259551265.731 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:05.731Z,1259551265.731 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:06.128Z,1259551266.128 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:06.128Z,1259551266.128 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:06.129Z,1259551266.129 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:06.129Z,1259551266.129 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:06.129Z,1259551266.129 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:06.129Z,1259551266.129 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:06.129Z,1259551266.129 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:06.129Z,1259551266.129 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:06.129Z,1259551266.129 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:06.129Z,1259551266.129 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:06.130Z,1259551266.130 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:06.130Z,1259551266.130 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:06.130Z,1259551266.130 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:06.130Z,1259551266.130 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:06.130Z,1259551266.130 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:06.130Z,1259551266.130 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:06.130Z,1259551266.130 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:06.130Z,1259551266.130 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:06.131Z,1259551266.131 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:06.131Z,1259551266.131 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:06.556Z,1259551266.556 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:06.556Z,1259551266.556 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:06.556Z,1259551266.556 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:06.556Z,1259551266.556 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:06.556Z,1259551266.556 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:06.557Z,1259551266.557 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:06.557Z,1259551266.557 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:06.557Z,1259551266.557 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:06.557Z,1259551266.557 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:06.557Z,1259551266.557 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:06.558Z,1259551266.558 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:21:06.558Z,1259551266.558 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:06.558Z,1259551266.558 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:06.558Z,1259551266.558 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:06.558Z,1259551266.558 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:06.559Z,1259551266.559 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:21:06.559Z,1259551266.559 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:06.559Z,1259551266.559 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:06.559Z,1259551266.559 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:06.559Z,1259551266.559 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:06.964Z,1259551266.964 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:06.964Z,1259551266.964 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:06.965Z,1259551266.965 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:06.966Z,1259551266.966 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:06.966Z,1259551266.966 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:06.966Z,1259551266.966 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:06.966Z,1259551266.966 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:06.966Z,1259551266.966 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:06.966Z,1259551266.966 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:06.966Z,1259551266.966 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:06.967Z,1259551266.967 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:06.967Z,1259551266.967 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:07.360Z,1259551267.360 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:07.360Z,1259551267.360 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:07.360Z,1259551267.360 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:07.360Z,1259551267.360 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:07.360Z,1259551267.360 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:07.361Z,1259551267.361 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:07.361Z,1259551267.361 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:07.361Z,1259551267.361 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:07.361Z,1259551267.361 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:07.361Z,1259551267.361 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:07.362Z,1259551267.362 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:21:07.362Z,1259551267.362 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:07.362Z,1259551267.362 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:07.362Z,1259551267.362 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:07.362Z,1259551267.362 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:07.363Z,1259551267.363 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:21:07.363Z,1259551267.363 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:07.363Z,1259551267.363 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:07.363Z,1259551267.363 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:07.363Z,1259551267.363 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:07.765Z,1259551267.765 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:07.766Z,1259551267.766 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:07.767Z,1259551267.767 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:07.767Z,1259551267.767 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:07.767Z,1259551267.767 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:07.767Z,1259551267.767 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:07.767Z,1259551267.767 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:07.767Z,1259551267.767 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:07.767Z,1259551267.767 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:07.767Z,1259551267.767 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:07.768Z,1259551267.768 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:07.768Z,1259551267.768 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:08.152Z,1259551268.152 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:08.152Z,1259551268.152 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:08.152Z,1259551268.152 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:08.152Z,1259551268.152 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:08.152Z,1259551268.152 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:08.153Z,1259551268.153 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:08.153Z,1259551268.153 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:08.153Z,1259551268.153 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:08.153Z,1259551268.153 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:08.153Z,1259551268.153 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:08.154Z,1259551268.154 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:21:08.154Z,1259551268.154 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:08.154Z,1259551268.154 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:08.154Z,1259551268.154 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:08.154Z,1259551268.154 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:08.155Z,1259551268.155 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:21:08.155Z,1259551268.155 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:08.155Z,1259551268.155 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:08.155Z,1259551268.155 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:08.155Z,1259551268.155 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:08.564Z,1259551268.564 [joystick_backseat:UpdateRudder:B](INFO): Updating rudder angle 0.000000 arcdeg 
2009-11-30T03:21:08.564Z,1259551268.564 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateRudder](INFO): Completed joystick_backseat:UpdateRudder
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateHeading] Running Loop=1
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::initialize joystick_backseat:UpdateHeading
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateHeading:A] Running Loop=1
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateHeading:A] Stopped
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateHeading:B] Running Loop=1
2009-11-30T03:21:08.565Z,1259551268.565 [joystick_backseat:UpdateCommandMode] Running Loop=1
2009-11-30T03:21:08.566Z,1259551268.566 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::initialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:08.566Z,1259551268.566 [joystick_backseat:UpdateCommandMode:A] Running Loop=1
2009-11-30T03:21:08.566Z,1259551268.566 [joystick_backseat:UpdateCommandMode:A] Stopped
2009-11-30T03:21:08.566Z,1259551268.566 [joystick_backseat:UpdateCommandMode:B] Running Loop=1
2009-11-30T03:21:08.566Z,1259551268.566 [joystick_backseat:UpdateSpeed] Running Loop=1
2009-11-30T03:21:08.566Z,1259551268.566 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::initialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:08.566Z,1259551268.566 [joystick_backseat:UpdateSpeed:A] Running Loop=1
2009-11-30T03:21:08.567Z,1259551268.567 [joystick_backseat:UpdateSpeed:A] Stopped
2009-11-30T03:21:08.567Z,1259551268.567 [joystick_backseat:UpdateSpeed:B] Running Loop=1
2009-11-30T03:21:08.947Z,1259551268.947 [CommandExec](IMPORTANT): got command stop
2009-11-30T03:21:08.948Z,1259551268.948 [CommandExec](IMPORTANT): Scheduling is paused
2009-11-30T03:21:08.948Z,1259551268.948 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2009-11-30T03:21:09.012Z,1259551269.012 [joystick_backseat:UpdateRudder] Running Loop=1
2009-11-30T03:21:09.013Z,1259551269.013 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::initialize joystick_backseat:UpdateRudder
2009-11-30T03:21:09.013Z,1259551269.013 [joystick_backseat:UpdateRudder:A] Running Loop=1
2009-11-30T03:21:09.013Z,1259551269.013 [joystick_backseat:UpdateRudder:A] Stopped
2009-11-30T03:21:09.013Z,1259551269.013 [joystick_backseat:UpdateRudder:B] Running Loop=1
2009-11-30T03:21:09.013Z,1259551269.013 [joystick_backseat:UpdateHeading:B](INFO): Updating heading 0.000000 arcdeg 
2009-11-30T03:21:09.014Z,1259551269.014 [joystick_backseat:UpdateHeading:B] Stopped
2009-11-30T03:21:09.014Z,1259551269.014 [joystick_backseat:UpdateHeading](INFO): Completed joystick_backseat:UpdateHeading
2009-11-30T03:21:09.014Z,1259551269.014 [joystick_backseat:UpdateHeading] Stopped
2009-11-30T03:21:09.014Z,1259551269.014 [joystick_backseat:UpdateHeading](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateHeading
2009-11-30T03:21:09.014Z,1259551269.014 [joystick_backseat:UpdateCommandMode:B](INFO): Updating horizontal command mode 0.000000 count 
2009-11-30T03:21:09.014Z,1259551269.014 [joystick_backseat:UpdateCommandMode:B] Stopped
2009-11-30T03:21:09.015Z,1259551269.015 [joystick_backseat:UpdateCommandMode](INFO): Completed joystick_backseat:UpdateCommandMode
2009-11-30T03:21:09.015Z,1259551269.015 [joystick_backseat:UpdateCommandMode] Stopped
2009-11-30T03:21:09.015Z,1259551269.015 [joystick_backseat:UpdateCommandMode](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateCommandMode
2009-11-30T03:21:09.015Z,1259551269.015 [joystick_backseat:UpdateSpeed:B](INFO): Updating vehicle speed 0.000000 m/s 
2009-11-30T03:21:09.015Z,1259551269.015 [joystick_backseat:UpdateSpeed:B] Stopped
2009-11-30T03:21:09.015Z,1259551269.015 [joystick_backseat:UpdateSpeed](INFO): Completed joystick_backseat:UpdateSpeed
2009-11-30T03:21:09.015Z,1259551269.015 [joystick_backseat:UpdateSpeed] Stopped
2009-11-30T03:21:09.016Z,1259551269.016 [joystick_backseat:UpdateSpeed](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateSpeed
2009-11-30T03:21:09.016Z,1259551269.016 [MissionManager](INFO): MissionManager is completed.
2009-11-30T03:21:09.016Z,1259551269.016 [MissionManager](INFO): Uninitializing Mission joystick_backseat
2009-11-30T03:21:09.016Z,1259551269.016 [joystick_backseat] Stopped
2009-11-30T03:21:09.016Z,1259551269.016 [joystick_backseat](DEBUG): Aggregate::uninitialize joystick_backseat
2009-11-30T03:21:09.016Z,1259551269.016 [joystick_backseat:UpdateRudder] Stopped
2009-11-30T03:21:09.016Z,1259551269.016 [joystick_backseat:UpdateRudder](DEBUG): Aggregate::uninitialize joystick_backseat:UpdateRudder
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:UpdateRudder:B] Stopped
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:H.BackseatDriver] Stopped
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:H.BackseatDriver](DEBUG): Uninitialize.
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl] Stopped
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl](DEBUG): Aggregate::uninitialize joystick_backseat:JoystickControl
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl:A.Buoyancy] Stopped
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl:B.SetSpeed] Stopped
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl:B.SetSpeed](DEBUG): Uninitialize.
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl:C.Mass] Stopped
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl:C.Mass](DEBUG): Uninitialize.
2009-11-30T03:21:09.017Z,1259551269.017 [joystick_backseat:JoystickControl:D.Pitch] Stopped
2009-11-30T03:21:09.018Z,1259551269.018 [joystick_backseat:JoystickControl:CmdHeading] Stopped
2009-11-30T03:21:09.018Z,1259551269.018 [joystick_backseat:JoystickControl:CmdHeading](DEBUG): Aggregate::uninitialize joystick_backseat:JoystickControl:CmdHeading
2009-11-30T03:21:09.018Z,1259551269.018 [joystick_backseat:JoystickControl:CmdHeading:A.Point] Stopped
2009-11-30T03:21:09.018Z,1259551269.018 [joystick_backseat:JoystickControl:CheckIn] Stopped
2009-11-30T03:21:09.018Z,1259551269.018 [joystick_backseat:JoystickControl:CheckIn](DEBUG): Aggregate::uninitialize joystick_backseat:JoystickControl:CheckIn
2009-11-30T03:21:09.018Z,1259551269.018 [joystick_backseat:JoystickControl:CheckIn:Read_GPS] Stopped
2009-11-30T03:21:09.351Z,1259551269.351 [MissionManager](IMPORTANT): Started mission Default
2009-11-30T03:21:09.351Z,1259551269.351 [Default] Running Loop=1
2009-11-30T03:21:09.352Z,1259551269.352 [Default](DEBUG): Aggregate::initialize Default
2009-11-30T03:21:09.352Z,1259551269.352 [Default:B.GoToSurface] Running Loop=1
2009-11-30T03:21:09.352Z,1259551269.352 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2009-11-30T03:21:09.352Z,1259551269.352 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2009-11-30T03:21:09.352Z,1259551269.352 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2009-11-30T03:21:09.353Z,1259551269.353 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2009-11-30T03:21:09.353Z,1259551269.353 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2009-11-30T03:21:09.353Z,1259551269.353 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2009-11-30T03:21:09.354Z,1259551269.354 [Default:A.Wait] Running Loop=1
2009-11-30T03:21:09.354Z,1259551269.354 [Default:A.Wait](DEBUG): Initialize Wait Component.
2009-11-30T03:21:22.399Z,1259551282.399 [Default:A.Wait](INFO): Done Waiting.
2009-11-30T03:21:22.399Z,1259551282.399 [Default:A.Wait] Stopped
2009-11-30T03:21:22.399Z,1259551282.399 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2009-11-30T03:21:22.801Z,1259551282.801 [Default:CheckIn] Running Loop=1
2009-11-30T03:21:22.801Z,1259551282.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2009-11-30T03:21:22.801Z,1259551282.801 [Default:CheckIn:Read_GPS] Running Loop=1
2009-11-30T03:21:38.978Z,1259551298.978 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:21:38.978Z,1259551298.978 [MassServo] No Fault, FailCount= 5
2009-11-30T03:21:42.260Z,1259551302.260 [MassServo](FAULT): Overload Error
2009-11-30T03:21:42.260Z,1259551302.260 [MassServo] Hardware Fault, FailCount= 1
2009-11-30T03:21:42.260Z,1259551302.260 [MassServo](ERROR): Hardware Fault
2009-11-30T03:21:42.264Z,1259551302.264 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:21:42.623Z,1259551302.623 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:21:42.623Z,1259551302.623 [MassServo](INFO): Powering down
2009-11-30T03:21:43.452Z,1259551303.452 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:21:43.573Z,1259551303.573 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:21:43.577Z,1259551303.577 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:21:43.577Z,1259551303.577 [MassServo] No Fault, FailCount= 1
2009-11-30T03:21:47.576Z,1259551307.576 [MassServo](FAULT): Overload Error
2009-11-30T03:21:47.576Z,1259551307.576 [MassServo] Hardware Fault, FailCount= 2
2009-11-30T03:21:47.577Z,1259551307.577 [MassServo](ERROR): Hardware Fault
2009-11-30T03:21:47.580Z,1259551307.580 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:21:47.843Z,1259551307.843 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:21:47.843Z,1259551307.843 [MassServo](INFO): Powering down
2009-11-30T03:21:48.658Z,1259551308.658 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:21:48.773Z,1259551308.773 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:21:48.776Z,1259551308.776 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:21:48.776Z,1259551308.776 [MassServo] No Fault, FailCount= 2
2009-11-30T03:21:53.125Z,1259551313.125 [MassServo](FAULT): Overload Error
2009-11-30T03:21:53.125Z,1259551313.125 [MassServo] Hardware Fault, FailCount= 3
2009-11-30T03:21:53.125Z,1259551313.125 [MassServo](ERROR): Hardware Fault
2009-11-30T03:21:53.128Z,1259551313.128 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:21:53.502Z,1259551313.502 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:21:53.502Z,1259551313.502 [MassServo](INFO): Powering down
2009-11-30T03:21:54.362Z,1259551314.362 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:21:54.481Z,1259551314.481 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:21:54.485Z,1259551314.485 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:21:54.485Z,1259551314.485 [MassServo] No Fault, FailCount= 3
2009-11-30T03:21:55.506Z,1259551315.506 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2009-11-30T03:21:58.780Z,1259551318.780 [MassServo](FAULT): Overload Error
2009-11-30T03:21:58.780Z,1259551318.780 [MassServo] Hardware Fault, FailCount= 4
2009-11-30T03:21:58.780Z,1259551318.780 [MassServo](ERROR): Hardware Fault
2009-11-30T03:21:58.784Z,1259551318.784 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:21:59.170Z,1259551319.170 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:21:59.170Z,1259551319.170 [MassServo](INFO): Powering down
2009-11-30T03:21:59.961Z,1259551319.961 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:22:00.081Z,1259551320.081 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:22:00.084Z,1259551320.084 [CBIT](INFO): Clearing failed state for component MassServo
2009-11-30T03:22:00.084Z,1259551320.084 [MassServo] No Fault, FailCount= 4
2009-11-30T03:22:04.452Z,1259551324.452 [MassServo](FAULT): Overload Error
2009-11-30T03:22:04.452Z,1259551324.452 [MassServo] Hardware Fault, FailCount= 5
2009-11-30T03:22:04.452Z,1259551324.452 [MassServo](ERROR): Hardware Fault
2009-11-30T03:22:04.456Z,1259551324.456 [CBIT](ERROR): Hardware Fault in component: MassServo
2009-11-30T03:22:04.456Z,1259551324.456 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2009-11-30T03:22:04.845Z,1259551324.845 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:22:04.845Z,1259551324.845 [MassServo](INFO): Powering down
2009-11-30T03:22:04.847Z,1259551324.847 [CommandExec](FAULT): Scheduling is paused
2009-11-30T03:22:04.848Z,1259551324.848 [CBIT](INFO): Critical error at 20091130T032204
2009-11-30T03:22:05.624Z,1259551325.624 [MassServo](DEBUG): Initializing EZServoServo.
2009-11-30T03:22:05.745Z,1259551325.745 [MassServo](DEBUG): Initializing MassServo.
2009-11-30T03:22:13.738Z,1259551333.738 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2009-11-30T03:22:13.739Z,1259551333.739 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2009-11-30T03:22:13.739Z,1259551333.739 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2009-11-30T03:22:13.757Z,1259551333.757 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2009-11-30T03:22:14.129Z,1259551334.129 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2009-11-30T03:22:14.129Z,1259551334.129 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2009-11-30T03:22:29.252Z,1259551349.252 [CommandExec](IMPORTANT): got command quit
2009-11-30T03:22:30.256Z,1259551350.256 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:30.256Z,1259551350.256 [CommandExec](INFO): Uninitializing the command executive.
2009-11-30T03:22:30.256Z,1259551350.256 [CommandExec](INFO): Uninitializing the command scheduler.
2009-11-30T03:22:30.256Z,1259551350.256 [CommandExec ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:30.380Z,1259551350.380 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2009-11-30T03:22:30.380Z,1259551350.380 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2009-11-30T03:22:30.380Z,1259551350.380 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:30.381Z,1259551350.381 [NavChartDb](INFO): Join timeout helper Thread ID is 2715
2009-11-30T03:22:30.420Z,1259551350.420 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:30.420Z,1259551350.420 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:30.424Z,1259551350.424 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2009-11-30T03:22:30.424Z,1259551350.424 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:30.424Z,1259551350.424 [Radio_Surface](INFO): Join timeout helper Thread ID is 2716
2009-11-30T03:22:30.536Z,1259551350.536 [Radio_Surface](INFO): Powering down
2009-11-30T03:22:30.537Z,1259551350.537 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:30.537Z,1259551350.537 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:30.544Z,1259551350.544 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2009-11-30T03:22:30.544Z,1259551350.544 [Onboard ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:30.544Z,1259551350.544 [Onboard](INFO): Join timeout helper Thread ID is 2717
2009-11-30T03:22:31.476Z,1259551351.476 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2009-11-30T03:22:31.772Z,1259551351.772 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:31.772Z,1259551351.772 [Onboard ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:31.776Z,1259551351.776 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2009-11-30T03:22:31.776Z,1259551351.776 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:31.776Z,1259551351.776 [DataOverHttps](INFO): Join timeout helper Thread ID is 2718
2009-11-30T03:22:32.480Z,1259551352.480 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:32.480Z,1259551352.480 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.500Z,1259551352.500 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2009-11-30T03:22:32.500Z,1259551352.500 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.500Z,1259551352.500 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2719
2009-11-30T03:22:32.776Z,1259551352.776 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:32.776Z,1259551352.776 [CTD_Seabird](INFO): Powering down
2009-11-30T03:22:32.788Z,1259551352.788 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.812Z,1259551352.812 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2009-11-30T03:22:32.812Z,1259551352.812 [logger ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.812Z,1259551352.812 [logger](INFO): Join timeout helper Thread ID is 2720
2009-11-30T03:22:32.835Z,1259551352.835 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:32.835Z,1259551352.835 [logger ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.841Z,1259551352.841 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2009-11-30T03:22:32.841Z,1259551352.841 [CommandLine ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.841Z,1259551352.841 [CommandLine](INFO): Join timeout helper Thread ID is 2721
2009-11-30T03:22:32.928Z,1259551352.928 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:32.928Z,1259551352.928 [CommandLine ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.941Z,1259551352.941 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2009-11-30T03:22:32.941Z,1259551352.941 [CommandExec ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.941Z,1259551352.941 [CommandExec](INFO): Join timeout helper Thread ID is 2722
2009-11-30T03:22:32.942Z,1259551352.942 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2009-11-30T03:22:32.942Z,1259551352.942 [controlThread ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:32.943Z,1259551352.943 [controlThread](INFO): Join timeout helper Thread ID is 2723
2009-11-30T03:22:33.056Z,1259551353.056 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2009-11-30T03:22:33.056Z,1259551353.056 [controlThread](DEBUG): Uninitializing ControlThread
2009-11-30T03:22:33.059Z,1259551353.059 [NAL9602](INFO): Powering down
2009-11-30T03:22:33.060Z,1259551353.060 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2009-11-30T03:22:33.061Z,1259551353.061 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2009-11-30T03:22:33.061Z,1259551353.061 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2009-11-30T03:22:33.062Z,1259551353.062 [MissionManager](INFO): Uninitializing Mission Default
2009-11-30T03:22:33.062Z,1259551353.062 [Default] Stopped
2009-11-30T03:22:33.062Z,1259551353.062 [Default](DEBUG): Aggregate::uninitialize Default
2009-11-30T03:22:33.062Z,1259551353.062 [Default:B.GoToSurface] Stopped
2009-11-30T03:22:33.062Z,1259551353.062 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2009-11-30T03:22:33.062Z,1259551353.062 [Default:CheckIn] Stopped
2009-11-30T03:22:33.062Z,1259551353.062 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2009-11-30T03:22:33.062Z,1259551353.062 [Default:CheckIn:Read_GPS] Stopped
2009-11-30T03:22:33.065Z,1259551353.065 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2009-11-30T03:22:33.065Z,1259551353.065 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2009-11-30T03:22:33.065Z,1259551353.065 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2009-11-30T03:22:33.066Z,1259551353.066 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2009-11-30T03:22:33.066Z,1259551353.066 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2009-11-30T03:22:33.066Z,1259551353.066 [BuoyancyServo](INFO): Powering down
2009-11-30T03:22:33.080Z,1259551353.080 [MassServo](DEBUG): Uninitialize Mass Servo.
2009-11-30T03:22:33.080Z,1259551353.080 [MassServo](INFO): Powering down
2009-11-30T03:22:33.081Z,1259551353.081 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2009-11-30T03:22:33.081Z,1259551353.081 [ThrusterHE](INFO): Powering down
2009-11-30T03:22:33.082Z,1259551353.082 [SBIT](DEBUG): Uninitialize SBIT Component.
2009-11-30T03:22:33.082Z,1259551353.082 [IBIT](DEBUG): Uninitialize IBIT Component.
2009-11-30T03:22:33.082Z,1259551353.082 [CBIT](DEBUG): Uninitialize CBIT Component.
2009-11-30T03:22:33.083Z,1259551353.083 [CBIT](DEBUG): Powering off loads.
2009-11-30T03:22:33.096Z,1259551353.096 [CBIT](DEBUG): Disabling WDT.
2009-11-30T03:22:33.108Z,1259551353.108 [CBIT](DEBUG): Opening all GF detection circuits.
2009-11-30T03:22:33.108Z,1259551353.108 [controlThread ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:33.274Z,1259551353.274 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:33.303Z,1259551353.303 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:33.305Z,1259551353.305 [Onboard ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:33.310Z,1259551353.310 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:33.395Z,1259551353.395 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2009-11-30T03:22:33.444Z,1259551353.444 [logger ThreadHandler](INFO): Thread cancelled.