2015-07-10T04:30:28.337Z,1436502628.337 [Supervisor](DEBUG): Initializing supervisor.
2015-07-10T04:30:28.341Z,1436502628.341 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-07-10T04:30:28.341Z,1436502628.341 [SyncHandler](INFO): Protected caller Thread ID is 765
2015-07-10T04:30:28.342Z,1436502628.342 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-07-10T04:30:28.344Z,1436502628.344 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-07-10T04:30:28.345Z,1436502628.345 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 766
2015-07-10T04:30:28.348Z,1436502628.348 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-07-10T04:30:28.360Z,1436502628.360 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-07-10T04:30:28.364Z,1436502628.364 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-07-10T04:30:28.365Z,1436502628.365 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 767
2015-07-10T04:30:28.366Z,1436502628.366 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-07-10T04:30:28.368Z,1436502628.368 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-07-10T04:30:28.369Z,1436502628.369 [logger ThreadHandler](INFO): Protected caller Thread ID is 768
2015-07-10T04:30:28.371Z,1436502628.371 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-07-10T04:30:28.371Z,1436502628.371 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-07-10T04:30:28.374Z,1436502628.374 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-07-10T04:30:28.774Z,1436502628.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-07-10T04:30:28.775Z,1436502628.775 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-07-10T04:30:28.974Z,1436502628.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-07-10T04:30:28.976Z,1436502628.976 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-07-10T04:30:29.102Z,1436502629.102 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-07-10T04:30:29.103Z,1436502629.103 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-07-10T04:30:29.243Z,1436502629.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-07-10T04:30:29.244Z,1436502629.244 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-07-10T04:30:29.339Z,1436502629.339 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-07-10T04:30:29.563Z,1436502629.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-07-10T04:30:29.564Z,1436502629.564 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-07-10T04:30:29.663Z,1436502629.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-07-10T04:30:29.664Z,1436502629.664 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-07-10T04:30:29.940Z,1436502629.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-07-10T04:30:29.940Z,1436502629.940 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-07-10T04:30:30.369Z,1436502630.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-07-10T04:30:30.369Z,1436502630.369 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-07-10T04:30:30.729Z,1436502630.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-07-10T04:30:30.730Z,1436502630.730 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-07-10T04:30:31.318Z,1436502631.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-07-10T04:30:31.319Z,1436502631.319 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-07-10T04:30:31.562Z,1436502631.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-07-10T04:30:31.563Z,1436502631.563 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-07-10T04:30:31.683Z,1436502631.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-07-10T04:30:31.683Z,1436502631.683 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-07-10T04:30:32.203Z,1436502632.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-07-10T04:30:32.204Z,1436502632.204 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-07-10T04:30:32.331Z,1436502632.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-07-10T04:30:32.333Z,1436502632.333 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-07-10T04:30:32.335Z,1436502632.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-07-10T04:30:32.604Z,1436502632.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-07-10T04:30:32.605Z,1436502632.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-07-10T04:30:32.740Z,1436502632.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-07-10T04:30:32.849Z,1436502632.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-07-10T04:30:32.961Z,1436502632.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-07-10T04:30:33.137Z,1436502633.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-07-10T04:30:33.293Z,1436502633.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-07-10T04:30:33.410Z,1436502633.410 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-07-10T04:30:33.514Z,1436502633.514 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-07-10T04:30:33.608Z,1436502633.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-07-10T04:30:33.718Z,1436502633.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-07-10T04:30:33.930Z,1436502633.930 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-07-10T04:30:33.934Z,1436502633.934 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-07-10T04:30:34.008Z,1436502634.008 [VerticalControl](DEBUG): Construct VerticalControl.
2015-07-10T04:30:34.146Z,1436502634.146 [VerticalControl] Loaded
2015-07-10T04:30:34.147Z,1436502634.147 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-07-10T04:30:34.147Z,1436502634.147 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-07-10T04:30:34.223Z,1436502634.223 [HorizontalControl] Loaded
2015-07-10T04:30:34.223Z,1436502634.223 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-07-10T04:30:34.224Z,1436502634.224 [SpeedControl](DEBUG): Construct SpeedControl.
2015-07-10T04:30:34.234Z,1436502634.234 [SpeedControl] Loaded
2015-07-10T04:30:34.234Z,1436502634.234 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-07-10T04:30:34.235Z,1436502634.235 [LoopControl](DEBUG): Construct LoopControl.
2015-07-10T04:30:34.235Z,1436502634.235 [LoopControl] Loaded
2015-07-10T04:30:34.236Z,1436502634.236 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-07-10T04:30:34.236Z,1436502634.236 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-07-10T04:30:34.237Z,1436502634.237 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-07-10T04:30:34.396Z,1436502634.396 [SBIT](DEBUG): Construct Startup Built In Test.
2015-07-10T04:30:34.413Z,1436502634.413 [SBIT] Loaded
2015-07-10T04:30:34.413Z,1436502634.413 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-07-10T04:30:34.414Z,1436502634.414 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-07-10T04:30:34.444Z,1436502634.444 [IBIT] Loaded
2015-07-10T04:30:34.444Z,1436502634.444 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-07-10T04:30:34.450Z,1436502634.450 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-07-10T04:30:34.608Z,1436502634.608 [CBIT] Loaded
2015-07-10T04:30:34.608Z,1436502634.608 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-07-10T04:30:34.609Z,1436502634.609 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-07-10T04:30:34.609Z,1436502634.609 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-07-10T04:30:34.642Z,1436502634.642 [DepthRateCalculator] Loaded
2015-07-10T04:30:34.642Z,1436502634.642 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-07-10T04:30:34.647Z,1436502634.647 [PitchRateCalculator] Loaded
2015-07-10T04:30:34.648Z,1436502634.648 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-07-10T04:30:34.659Z,1436502634.659 [SpeedCalculator] Loaded
2015-07-10T04:30:34.660Z,1436502634.660 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-07-10T04:30:34.676Z,1436502634.676 [TempGradientCalculator] Loaded
2015-07-10T04:30:34.677Z,1436502634.677 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-07-10T04:30:34.682Z,1436502634.682 [YawRateCalculator] Loaded
2015-07-10T04:30:34.682Z,1436502634.682 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-07-10T04:30:34.683Z,1436502634.683 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-07-10T04:30:34.684Z,1436502634.684 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-07-10T04:30:34.778Z,1436502634.778 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-07-10T04:30:34.779Z,1436502634.779 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-07-10T04:30:35.066Z,1436502635.066 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-07-10T04:30:35.067Z,1436502635.067 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-07-10T04:30:35.197Z,1436502635.197 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-07-10T04:30:35.197Z,1436502635.197 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-07-10T04:30:35.262Z,1436502635.262 [DeadReckonUsingSpeedCalculator] Loaded
2015-07-10T04:30:35.262Z,1436502635.262 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-07-10T04:30:35.280Z,1436502635.280 [NavChart] Loaded
2015-07-10T04:30:35.280Z,1436502635.280 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-07-10T04:30:35.285Z,1436502635.285 [UniversalFixResidualReporter] Loaded
2015-07-10T04:30:35.285Z,1436502635.285 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-07-10T04:30:35.285Z,1436502635.285 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-07-10T04:30:35.286Z,1436502635.286 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-07-10T04:30:35.294Z,1436502635.294 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-07-10T04:30:35.295Z,1436502635.295 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-07-10T04:30:35.524Z,1436502635.524 [Aanderaa_O2] Loaded
2015-07-10T04:30:35.524Z,1436502635.524 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2015-07-10T04:30:35.535Z,1436502635.535 [CANONSampler] Loaded
2015-07-10T04:30:35.536Z,1436502635.536 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2015-07-10T04:30:35.621Z,1436502635.621 [CTD_NeilBrown] Loaded
2015-07-10T04:30:35.622Z,1436502635.622 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-07-10T04:30:35.624Z,1436502635.624 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405894E0
2015-07-10T04:30:35.624Z,1436502635.624 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 847
2015-07-10T04:30:35.627Z,1436502635.627 [ESPComponent](INFO): readConfigTimespan: ESPComponent.poTimeout = 150.000000 secs
2015-07-10T04:30:35.628Z,1436502635.628 [ESPComponent](INFO): readConfigTimespan: ESPComponent.sampleTimeout = 4200.000000 secs
2015-07-10T04:30:35.637Z,1436502635.637 [ESPComponent] Loaded
2015-07-10T04:30:35.638Z,1436502635.638 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2015-07-10T04:30:35.657Z,1436502635.657 [PAR_Licor] Loaded
2015-07-10T04:30:35.658Z,1436502635.658 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-07-10T04:30:35.709Z,1436502635.709 [WetLabsBB2FL] Loaded
2015-07-10T04:30:35.709Z,1436502635.709 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-07-10T04:30:35.712Z,1436502635.712 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405B94E0
2015-07-10T04:30:35.712Z,1436502635.712 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 848
2015-07-10T04:30:35.713Z,1436502635.713 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-07-10T04:30:35.714Z,1436502635.714 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-07-10T04:30:36.246Z,1436502636.246 [AcousticModem_Benthos_ATM900] Loaded
2015-07-10T04:30:36.247Z,1436502636.247 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2015-07-10T04:30:36.423Z,1436502636.423 [DataOverHttps] Loaded
2015-07-10T04:30:36.423Z,1436502636.423 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-07-10T04:30:36.441Z,1436502636.441 [Depth_Keller] Loaded
2015-07-10T04:30:36.442Z,1436502636.442 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-07-10T04:30:36.447Z,1436502636.447 [DropWeight] Loaded
2015-07-10T04:30:36.447Z,1436502636.447 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-07-10T04:30:36.551Z,1436502636.551 [NAL9602] Loaded
2015-07-10T04:30:36.551Z,1436502636.551 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-07-10T04:30:36.598Z,1436502636.598 [Onboard] Loaded
2015-07-10T04:30:36.599Z,1436502636.599 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-07-10T04:30:36.610Z,1436502636.610 [Radio_Surface] Loaded
2015-07-10T04:30:36.610Z,1436502636.610 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-07-10T04:30:36.613Z,1436502636.613 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407FD4E0
2015-07-10T04:30:36.614Z,1436502636.614 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 849
2015-07-10T04:30:36.827Z,1436502636.827 [PNI_TCM] Loaded
2015-07-10T04:30:36.827Z,1436502636.827 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-07-10T04:30:37.025Z,1436502637.025 [Rowe_600] Loaded
2015-07-10T04:30:37.025Z,1436502637.025 [ComponentRegistry](DEBUG): Component "Rowe_600" handled in its own thread.
2015-07-10T04:30:37.030Z,1436502637.030 [Rowe_600 ThreadHandler](DEBUG): Created PCaller Thread at 4082D4E0
2015-07-10T04:30:37.030Z,1436502637.030 [Rowe_600 ThreadHandler](INFO): Protected caller Thread ID is 850
2015-07-10T04:30:39.122Z,1436502639.122 [BPC1] Loaded
2015-07-10T04:30:39.122Z,1436502639.122 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-07-10T04:30:39.123Z,1436502639.123 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-07-10T04:30:39.124Z,1436502639.124 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-07-10T04:30:39.282Z,1436502639.282 [BuoyancyServo] Loaded
2015-07-10T04:30:39.283Z,1436502639.283 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-07-10T04:30:39.295Z,1436502639.295 [ElevatorServo] Loaded
2015-07-10T04:30:39.296Z,1436502639.296 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-07-10T04:30:39.312Z,1436502639.312 [MassServo] Loaded
2015-07-10T04:30:39.312Z,1436502639.312 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-07-10T04:30:39.325Z,1436502639.325 [RudderServo] Loaded
2015-07-10T04:30:39.325Z,1436502639.325 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-07-10T04:30:39.337Z,1436502639.337 [ThrusterServo] Loaded
2015-07-10T04:30:39.337Z,1436502639.337 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-07-10T04:30:39.338Z,1436502639.338 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-07-10T04:30:39.338Z,1436502639.338 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-07-10T04:30:39.483Z,1436502639.483 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-07-10T04:30:39.484Z,1436502639.484 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-07-10T04:30:39.526Z,1436502639.526 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-07-10T04:30:39.530Z,1436502639.530 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-07-10T04:30:39.531Z,1436502639.531 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-07-10T04:30:39.538Z,1436502639.538 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-07-10T04:30:39.542Z,1436502639.542 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408DB4E0
2015-07-10T04:30:39.542Z,1436502639.542 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 851
2015-07-10T04:30:39.547Z,1436502639.547 [Supervisor](INFO): Main Thread ID is 764
2015-07-10T04:30:39.547Z,1436502639.547 [Supervisor](DEBUG): Running supervisor.
2015-07-10T04:30:39.548Z,1436502639.548 [CommandLine ThreadHandler](INFO): Handler Thread ID is 852
2015-07-10T04:30:39.554Z,1436502639.554 [controlThread ThreadHandler](INFO): Handler Thread ID is 853
2015-07-10T04:30:39.555Z,1436502639.555 [controlThread](DEBUG): Initializing ControlThread
2015-07-10T04:30:39.556Z,1436502639.556 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-07-10T04:30:39.558Z,1436502639.558 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-07-10T04:30:39.558Z,1436502639.558 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-07-10T04:30:39.559Z,1436502639.559 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-07-10T04:30:39.559Z,1436502639.559 [SBIT](INFO): Initialize SBIT Component.
2015-07-10T04:30:39.560Z,1436502639.560 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12384
2015-07-10T04:30:39.560Z,1436502639.560 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-07-10T04:30:39.560Z,1436502639.560 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-07-10T04:30:39.561Z,1436502639.561 [SBIT](INFO): Beginning SBIT in 57.000000 seconds.
2015-07-10T04:30:39.562Z,1436502639.562 [IBIT](INFO): Initialize IBIT Component.
2015-07-10T04:30:39.563Z,1436502639.563 [CBIT](DEBUG): Initialize CBIT Component.
2015-07-10T04:30:39.563Z,1436502639.563 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-07-10T04:30:39.563Z,1436502639.563 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-07-10T04:30:39.566Z,1436502639.566 [logger ThreadHandler](INFO): Handler Thread ID is 854
2015-07-10T04:30:39.586Z,1436502639.586 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-07-10T04:30:39.587Z,1436502639.587 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-07-10T04:30:39.587Z,1436502639.587 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-07-10T04:30:39.587Z,1436502639.587 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-07-10T04:30:39.588Z,1436502639.588 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-07-10T04:30:39.588Z,1436502639.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-07-10T04:30:39.589Z,1436502639.589 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-07-10T04:30:39.589Z,1436502639.589 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-07-10T04:30:39.590Z,1436502639.590 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-07-10T04:30:39.590Z,1436502639.590 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-07-10T04:30:39.590Z,1436502639.590 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-07-10T04:30:39.591Z,1436502639.591 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-07-10T04:30:39.591Z,1436502639.591 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-07-10T04:30:39.615Z,1436502639.615 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-07-10T04:30:39.631Z,1436502639.631 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 856
2015-07-10T04:30:39.632Z,1436502639.632 [WetLabsBB2FL](INFO): Powering down
2015-07-10T04:30:39.653Z,1436502639.653 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 855
2015-07-10T04:30:39.659Z,1436502639.659 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 857
2015-07-10T04:30:39.665Z,1436502639.665 [Radio_Surface](INFO): Powering up
2015-07-10T04:30:39.666Z,1436502639.666 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2015-07-10T04:30:39.671Z,1436502639.671 [Rowe_600 ThreadHandler](INFO): Handler Thread ID is 859
2015-07-10T04:30:39.671Z,1436502639.671 [Rowe_600](INFO): Initializing
2015-07-10T04:30:39.673Z,1436502639.673 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2015-07-10T04:30:39.731Z,1436502639.731 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 860
2015-07-10T04:30:39.739Z,1436502639.739 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-07-10T04:30:39.741Z,1436502639.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-07-10T04:30:39.741Z,1436502639.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-07-10T04:30:39.741Z,1436502639.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-07-10T04:30:39.742Z,1436502639.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-07-10T04:30:39.742Z,1436502639.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-07-10T04:30:39.742Z,1436502639.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-07-10T04:30:39.743Z,1436502639.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-07-10T04:30:39.743Z,1436502639.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-07-10T04:30:39.795Z,1436502639.795 [Rowe_600](INFO): Powering up
2015-07-10T04:30:39.802Z,1436502639.802 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-07-10T04:30:39.839Z,1436502639.839 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <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>


2015-07-10T04:30:39.840Z,1436502639.840 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-07-10T04:30:39.910Z,1436502639.910 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2015-07-10T04:30:39.931Z,1436502639.931 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-07-10T04:30:39.985Z,1436502639.985 [Default:SplitLargeDefaultLogs:A.Execute](DEBUG): Construct Execute.
2015-07-10T04:30:39.989Z,1436502639.989 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-07-10T04:30:40.010Z,1436502640.010 [Default:CheckIn:WaitAnHour:A.Wait](DEBUG): Construct Wait.
2015-07-10T04:30:40.012Z,1436502640.012 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2015-07-10T04:30:40.045Z,1436502640.045 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

    <ReadDatum Id="Read_GPS">
        <Timeout Duration="P5M"/>
<!-- NOTE: Original Default.xml didn't have a timeout on the GPS read, so theoretically could have hung indefinitely there... -->
        <Universal:time_fix/>
    </ReadDatum>

    <Aggregate Id="ReportMinutesSinceMissionStarted">

        <When>
            <Elapsed><Universal:mission_started/></Elapsed>
            <Gt><Units:minute/><Value>10</Value></Gt>
            <And><Elapsed><Universal:time_fix/></Elapsed><Lt><Units:millisecond/><Value>500</Value></Lt></And>
        </When>

        <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>

    <Aggregate Id="SplitLargeDefaultLogs">

        <When>
            <Arg Name="ElapsedSinceDefaultStarted"/>
            <Gt><Units:day/><Value>1</Value></Gt>
        </When>

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

    </Aggregate>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

        <Aggregate Id="WaitAnHour">

            <When>
                <Universal:platform_conversation/>
                <Eq><False/></Eq>
            </When>

            <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Units:hour/><Value>1</Value></Setting>
            </Guidance:Wait>

        </Aggregate>

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

    </Aggregate>

</Mission>


2015-07-10T04:30:40.050Z,1436502640.050 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-07-10T04:30:40.098Z,1436502640.098 [CANONSampler](INFO): Powering down
2015-07-10T04:30:40.111Z,1436502640.111 [CTD_NeilBrown](DEBUG): CTD uart timeout: serial timeout
2015-07-10T04:30:40.111Z,1436502640.111 [CTD_NeilBrown](ERROR): Could not get real or simulated CTD data. startTime.elapsed() = 0.437386
2015-07-10T04:30:40.171Z,1436502640.171 [AcousticModem_Benthos_ATM900](INFO): Powering up
2015-07-10T04:30:40.171Z,1436502640.171 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2015-07-10T04:30:40.697Z,1436502640.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:40.992Z,1436502640.992 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:41.114Z,1436502641.114 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-07-10T04:30:41.121Z,1436502641.121 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-07-10T04:30:41.143Z,1436502641.143 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-07-10T04:30:41.149Z,1436502641.149 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-07-10T04:30:41.163Z,1436502641.163 [MassServo](DEBUG): Initializing EZServoServo.
2015-07-10T04:30:41.173Z,1436502641.173 [MassServo](DEBUG): Initializing MassServo.
2015-07-10T04:30:41.191Z,1436502641.191 [RudderServo](DEBUG): Initializing EZServoServo.
2015-07-10T04:30:41.197Z,1436502641.197 [RudderServo](DEBUG): Initializing RudderServo.
2015-07-10T04:30:41.219Z,1436502641.219 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-07-10T04:30:41.225Z,1436502641.225 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-07-10T04:30:41.450Z,1436502641.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:41.451Z,1436502641.451 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:41.707Z,1436502641.707 [ESPComponent](DEBUG): runnable
2015-07-10T04:30:41.715Z,1436502641.715 [NAL9602](INFO): Powering up NAL9602
2015-07-10T04:30:41.746Z,1436502641.746 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:41.747Z,1436502641.747 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:42.114Z,1436502642.114 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2015-07-10T04:30:42.114Z,1436502642.114 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2015-07-10T04:30:42.114Z,1436502642.114 [BuoyancyServo] Communications Fault, FailCount= 1
2015-07-10T04:30:42.114Z,1436502642.114 [BuoyancyServo](ERROR): Communications Fault
2015-07-10T04:30:42.320Z,1436502642.320 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2015-07-10T04:30:42.326Z,1436502642.326 [ESPComponent](INFO): ESPComponent::stop
2015-07-10T04:30:42.326Z,1436502642.326 [ESPComponent](INFO): Powering down
2015-07-10T04:30:42.513Z,1436502642.513 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:42.523Z,1436502642.523 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:42.541Z,1436502642.541 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-07-10T04:30:42.551Z,1436502642.551 [BuoyancyServo](INFO): Powering down
2015-07-10T04:30:42.847Z,1436502642.847 [Aanderaa_O2](INFO): Powering down
2015-07-10T04:30:42.896Z,1436502642.896 [ESPComponent](INFO): ESPComponent::stopping
2015-07-10T04:30:42.959Z,1436502642.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:42.967Z,1436502642.967 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:43.222Z,1436502643.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:43.228Z,1436502643.228 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:43.561Z,1436502643.561 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:43.562Z,1436502643.562 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:43.984Z,1436502643.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:44.003Z,1436502644.003 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-07-10T04:30:44.961Z,1436502644.961 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2015-07-10T04:30:44.961Z,1436502644.961 [BuoyancyServo] No Fault, FailCount= 1
2015-07-10T04:30:45.291Z,1436502645.291 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-07-10T04:30:45.413Z,1436502645.413 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-07-10T04:30:47.786Z,1436502647.786 [AcousticModem_Benthos_ATM900](DEBUG): 
2015-07-10T04:30:49.344Z,1436502649.344 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2015-07-10T04:30:49.347Z,1436502649.347 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2015-07-10T04:30:49.391Z,1436502649.391 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2015-07-10T04:30:49.771Z,1436502649.771 [AcousticModem_Benthos_ATM900](DEBUG): Jul 10 2015 04:35:05 
2015-07-10T04:30:50.656Z,1436502650.656 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2015-07-10T04:30:51.093Z,1436502651.093 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-07-10T04:30:51.109Z,1436502651.109 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2015-07-10T04:30:51.109Z,1436502651.109 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2015-07-10T04:30:51.720Z,1436502651.720 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2015-07-10T04:30:52.317Z,1436502652.317 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2015-07-10T04:30:52.338Z,1436502652.338 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2015-07-10T04:30:52.949Z,1436502652.949 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2015-07-10T04:30:53.156Z,1436502653.156 [NAL9602](INFO): NAL9602 initialized
2015-07-10T04:30:56.550Z,1436502656.550 [Rowe_600](ERROR): Only MATLAB type 10 (float32) matrices are supported, got MATLAB type 47
2015-07-10T04:31:15.976Z,1436502675.976 [BPC1](ERROR): Failed to receive battery data
2015-07-10T04:31:15.976Z,1436502675.976 [BPC1] Communications Fault, FailCount= 1
2015-07-10T04:31:15.976Z,1436502675.976 [BPC1](ERROR): Communications Fault
2015-07-10T04:31:16.105Z,1436502676.105 [CBIT](ERROR): Communications Fault in component: BPC1
2015-07-10T04:31:17.609Z,1436502677.609 [CBIT](INFO): Clearing failed state for component BPC1
2015-07-10T04:31:17.610Z,1436502677.610 [BPC1] No Fault, FailCount= 1
2015-07-10T04:31:37.487Z,1436502697.487 [SBIT](IMPORTANT): Beginning Startup BIT
2015-07-10T04:31:37.520Z,1436502697.520 [CBIT](IMPORTANT): Beginning GF scan
2015-07-10T04:31:49.063Z,1436502709.063 [BPC1](ERROR): Failed to receive battery data
2015-07-10T04:31:49.063Z,1436502709.063 [BPC1] Communications Fault, FailCount= 1
2015-07-10T04:31:49.063Z,1436502709.063 [BPC1](ERROR): Communications Fault
2015-07-10T04:31:49.186Z,1436502709.186 [CBIT](ERROR): Communications Fault in component: BPC1
2015-07-10T04:31:50.398Z,1436502710.398 [CBIT](INFO): Clearing failed state for component BPC1
2015-07-10T04:31:50.398Z,1436502710.398 [BPC1] No Fault, FailCount= 1
2015-07-10T04:32:00.265Z,1436502720.265 [CommandLine](IMPORTANT): got command failComponent
2015-07-10T04:32:00.266Z,1436502720.266 [CommandLine](IMPORTANT): Failed components:
2015-07-10T04:32:00.266Z,1436502720.266 [CommandLine](IMPORTANT): No failed Components.
2015-07-10T04:32:01.920Z,1436502721.920 [BPC1](ERROR): Failed to parse message.
2015-07-10T04:32:01.920Z,1436502721.920 [BPC1](ERROR): Failed to parse bank B battery data
2015-07-10T04:32:01.920Z,1436502721.920 [BPC1] Data Fault, FailCount= 2
2015-07-10T04:32:01.920Z,1436502721.920 [BPC1](ERROR): Data Fault
2015-07-10T04:32:02.334Z,1436502722.334 [CBIT](ERROR): Data Fault in component: BPC1
2015-07-10T04:32:03.799Z,1436502723.799 [CBIT](INFO): Clearing failed state for component BPC1
2015-07-10T04:32:03.799Z,1436502723.799 [BPC1] No Fault, FailCount= 2
2015-07-10T04:32:05.059Z,1436502725.059 [CBIT](IMPORTANT): No ground fault detected
2015-07-10T04:32:20.514Z,1436502740.514 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:20.625Z,1436502740.625 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:20.838Z,1436502740.838 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:20.949Z,1436502740.949 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:21.058Z,1436502741.058 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:26.357Z,1436502746.357 [CommandLine](IMPORTANT): got command quit
2015-07-10T04:32:27.164Z,1436502747.164 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:27.267Z,1436502747.267 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:27.277Z,1436502747.277 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:27.365Z,1436502747.365 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2015-07-10T04:32:27.365Z,1436502747.365 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:27.383Z,1436502747.383 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:27.458Z,1436502747.458 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2015-07-10T04:32:27.458Z,1436502747.458 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:27.459Z,1436502747.459 [CommandLine](INFO): Join timeout helper Thread ID is 882
2015-07-10T04:32:27.482Z,1436502747.482 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2015-07-10T04:32:27.482Z,1436502747.482 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:27.482Z,1436502747.482 [NavChartDb](INFO): Join timeout helper Thread ID is 883
2015-07-10T04:32:27.487Z,1436502747.487 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:27.591Z,1436502747.591 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:27.695Z,1436502747.695 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-07-10T04:32:29.637Z,1436502749.637 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2015-07-10T04:32:29.638Z,1436502749.638 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:29.649Z,1436502749.649 [ComponentRegistry](INFO): Shutting down Rowe_600 ThreadHandler
2015-07-10T04:32:29.649Z,1436502749.649 [Rowe_600 ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:29.650Z,1436502749.650 [Rowe_600](INFO): Join timeout helper Thread ID is 884
2015-07-10T04:32:29.697Z,1436502749.697 [Rowe_600 ThreadHandler](INFO): Uninitializing protected caller thread.
2015-07-10T04:32:29.698Z,1436502749.698 [Rowe_600](INFO): Powering down
2015-07-10T04:32:29.770Z,1436502749.770 [Rowe_600 ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:29.785Z,1436502749.785 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2015-07-10T04:32:29.785Z,1436502749.785 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:29.786Z,1436502749.786 [Radio_Surface](INFO): Join timeout helper Thread ID is 885
2015-07-10T04:32:29.878Z,1436502749.878 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2015-07-10T04:32:29.878Z,1436502749.878 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:29.886Z,1436502749.886 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2015-07-10T04:32:29.886Z,1436502749.886 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:29.886Z,1436502749.886 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 886
2015-07-10T04:32:29.934Z,1436502749.934 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2015-07-10T04:32:29.934Z,1436502749.934 [WetLabsBB2FL](INFO): Powering down
2015-07-10T04:32:29.935Z,1436502749.935 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:29.943Z,1436502749.943 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2015-07-10T04:32:29.943Z,1436502749.943 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:29.943Z,1436502749.943 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 887
2015-07-10T04:32:30.190Z,1436502750.190 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2015-07-10T04:32:30.190Z,1436502750.190 [CTD_NeilBrown](INFO): Powering down
2015-07-10T04:32:30.191Z,1436502750.191 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:30.199Z,1436502750.199 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2015-07-10T04:32:30.199Z,1436502750.199 [logger ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:30.200Z,1436502750.200 [logger](INFO): Join timeout helper Thread ID is 888
2015-07-10T04:32:30.214Z,1436502750.214 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2015-07-10T04:32:30.214Z,1436502750.214 [logger ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:30.216Z,1436502750.216 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2015-07-10T04:32:30.216Z,1436502750.216 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:30.216Z,1436502750.216 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2015-07-10T04:32:30.216Z,1436502750.216 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:30.217Z,1436502750.217 [controlThread](INFO): Join timeout helper Thread ID is 889
2015-07-10T04:32:30.828Z,1436502750.828 [BPC1](ERROR): Failed to receive battery data
2015-07-10T04:32:30.828Z,1436502750.828 [BPC1] Communications Fault, FailCount= 3
2015-07-10T04:32:30.828Z,1436502750.828 [BPC1](ERROR): Communications Fault
2015-07-10T04:32:30.927Z,1436502750.927 [CBIT](ERROR): Communications Fault in component: BPC1
2015-07-10T04:32:30.929Z,1436502750.929 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2015-07-10T04:32:30.929Z,1436502750.929 [controlThread](DEBUG): Uninitializing ControlThread
2015-07-10T04:32:30.929Z,1436502750.929 [Aanderaa_O2](INFO): Powering down
2015-07-10T04:32:30.930Z,1436502750.930 [AcousticModem_Benthos_ATM900](INFO): Powering down
2015-07-10T04:32:31.056Z,1436502751.056 [NAL9602](INFO): Powering down
2015-07-10T04:32:31.057Z,1436502751.057 [PNI_TCM](INFO): Powering down
2015-07-10T04:32:31.145Z,1436502751.145 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-07-10T04:32:31.146Z,1436502751.146 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2015-07-10T04:32:31.149Z,1436502751.149 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2015-07-10T04:32:31.150Z,1436502751.150 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2015-07-10T04:32:31.150Z,1436502751.150 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2015-07-10T04:32:31.150Z,1436502751.150 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2015-07-10T04:32:31.151Z,1436502751.151 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-07-10T04:32:31.151Z,1436502751.151 [BuoyancyServo](INFO): Powering down
2015-07-10T04:32:31.164Z,1436502751.164 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-07-10T04:32:31.164Z,1436502751.164 [ElevatorServo](INFO): Powering down
2015-07-10T04:32:31.165Z,1436502751.165 [MassServo](DEBUG): Uninitialize Mass Servo.
2015-07-10T04:32:31.165Z,1436502751.165 [MassServo](INFO): Powering down
2015-07-10T04:32:31.166Z,1436502751.166 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-07-10T04:32:31.166Z,1436502751.166 [RudderServo](INFO): Powering down
2015-07-10T04:32:31.167Z,1436502751.167 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-07-10T04:32:31.167Z,1436502751.167 [ThrusterServo](INFO): Powering down
2015-07-10T04:32:31.168Z,1436502751.168 [SBIT](DEBUG): Uninitialize SBIT Component.
2015-07-10T04:32:31.168Z,1436502751.168 [IBIT](DEBUG): Uninitialize IBIT Component.
2015-07-10T04:32:31.168Z,1436502751.168 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-07-10T04:32:31.200Z,1436502751.200 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:31.266Z,1436502751.266 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:31.271Z,1436502751.271 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:31.345Z,1436502751.345 [Rowe_600 ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:31.353Z,1436502751.353 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:31.518Z,1436502751.518 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-07-10T04:32:31.577Z,1436502751.577 [logger ThreadHandler](INFO): Thread cancelled.