2015-05-18T18:52:26.613Z,1431975146.613 [Supervisor](DEBUG): Initializing supervisor.
2015-05-18T18:52:26.632Z,1431975146.632 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-05-18T18:52:26.633Z,1431975146.633 [SyncHandler](INFO): Protected caller Thread ID is 1894
2015-05-18T18:52:26.633Z,1431975146.633 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-05-18T18:52:26.648Z,1431975146.648 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-05-18T18:52:26.649Z,1431975146.649 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1895
2015-05-18T18:52:26.703Z,1431975146.703 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-05-18T18:52:26.759Z,1431975146.759 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-05-18T18:52:26.776Z,1431975146.776 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-05-18T18:52:26.777Z,1431975146.777 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1896
2015-05-18T18:52:26.777Z,1431975146.777 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-05-18T18:52:26.796Z,1431975146.796 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-05-18T18:52:26.797Z,1431975146.797 [logger ThreadHandler](INFO): Protected caller Thread ID is 1897
2015-05-18T18:52:26.799Z,1431975146.799 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-05-18T18:52:26.799Z,1431975146.799 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-05-18T18:52:26.809Z,1431975146.809 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-05-18T18:52:28.716Z,1431975148.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-05-18T18:52:28.717Z,1431975148.717 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-05-18T18:52:29.247Z,1431975149.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-05-18T18:52:29.248Z,1431975149.248 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-05-18T18:52:29.554Z,1431975149.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-05-18T18:52:29.555Z,1431975149.555 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-05-18T18:52:29.930Z,1431975149.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-05-18T18:52:29.931Z,1431975149.931 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-05-18T18:52:30.160Z,1431975150.160 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-05-18T18:52:30.688Z,1431975150.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-05-18T18:52:30.688Z,1431975150.688 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-05-18T18:52:30.920Z,1431975150.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-05-18T18:52:30.920Z,1431975150.920 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-05-18T18:52:31.647Z,1431975151.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-05-18T18:52:31.647Z,1431975151.647 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-05-18T18:52:32.909Z,1431975152.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-05-18T18:52:32.910Z,1431975152.910 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-05-18T18:52:33.944Z,1431975153.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-05-18T18:52:33.945Z,1431975153.945 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-05-18T18:52:35.736Z,1431975155.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-05-18T18:52:35.737Z,1431975155.737 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-05-18T18:52:36.366Z,1431975156.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-05-18T18:52:36.367Z,1431975156.367 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-05-18T18:52:36.678Z,1431975156.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-05-18T18:52:36.679Z,1431975156.679 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-05-18T18:52:38.153Z,1431975158.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-05-18T18:52:38.153Z,1431975158.153 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-05-18T18:52:38.483Z,1431975158.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-05-18T18:52:38.485Z,1431975158.485 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-05-18T18:52:38.487Z,1431975158.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-05-18T18:52:39.270Z,1431975159.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-05-18T18:52:39.270Z,1431975159.270 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-05-18T18:52:39.624Z,1431975159.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2015-05-18T18:52:39.870Z,1431975159.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-05-18T18:52:40.109Z,1431975160.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-05-18T18:52:40.413Z,1431975160.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-05-18T18:52:40.853Z,1431975160.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-05-18T18:52:41.383Z,1431975161.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-05-18T18:52:41.658Z,1431975161.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-05-18T18:52:41.892Z,1431975161.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-05-18T18:52:42.170Z,1431975162.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-05-18T18:52:42.413Z,1431975162.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-05-18T18:52:42.983Z,1431975162.983 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/LOGIN/
2015-05-18T18:52:42.983Z,1431975162.983 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-05-18T18:52:42.989Z,1431975162.989 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-05-18T18:52:43.146Z,1431975163.146 [VerticalControl](DEBUG): Construct VerticalControl.
2015-05-18T18:52:43.530Z,1431975163.530 [VerticalControl] Loaded
2015-05-18T18:52:43.530Z,1431975163.530 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-05-18T18:52:43.531Z,1431975163.531 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-05-18T18:52:43.789Z,1431975163.789 [HorizontalControl] Loaded
2015-05-18T18:52:43.790Z,1431975163.790 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-05-18T18:52:43.790Z,1431975163.790 [SpeedControl](DEBUG): Construct SpeedControl.
2015-05-18T18:52:43.820Z,1431975163.820 [SpeedControl] Loaded
2015-05-18T18:52:43.821Z,1431975163.821 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-05-18T18:52:43.821Z,1431975163.821 [LoopControl](DEBUG): Construct LoopControl.
2015-05-18T18:52:43.822Z,1431975163.822 [LoopControl] Loaded
2015-05-18T18:52:43.822Z,1431975163.822 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-05-18T18:52:43.823Z,1431975163.823 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-05-18T18:52:43.823Z,1431975163.823 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-05-18T18:52:44.254Z,1431975164.254 [SBIT](DEBUG): Construct Startup Built In Test.
2015-05-18T18:52:44.301Z,1431975164.301 [SBIT] Loaded
2015-05-18T18:52:44.302Z,1431975164.302 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-05-18T18:52:44.302Z,1431975164.302 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-05-18T18:52:44.403Z,1431975164.403 [IBIT] Loaded
2015-05-18T18:52:44.403Z,1431975164.403 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-05-18T18:52:44.406Z,1431975164.406 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-05-18T18:52:44.919Z,1431975164.919 [CBIT] Loaded
2015-05-18T18:52:44.920Z,1431975164.920 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-05-18T18:52:44.920Z,1431975164.920 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-05-18T18:52:44.921Z,1431975164.921 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-05-18T18:52:45.003Z,1431975165.003 [DepthRateCalculator] Loaded
2015-05-18T18:52:45.003Z,1431975165.003 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-05-18T18:52:45.029Z,1431975165.029 [PitchRateCalculator] Loaded
2015-05-18T18:52:45.029Z,1431975165.029 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-05-18T18:52:45.069Z,1431975165.069 [SpeedCalculator] Loaded
2015-05-18T18:52:45.069Z,1431975165.069 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-05-18T18:52:45.119Z,1431975165.119 [TempGradientCalculator] Loaded
2015-05-18T18:52:45.119Z,1431975165.119 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-05-18T18:52:45.124Z,1431975165.124 [YawRateCalculator] Loaded
2015-05-18T18:52:45.141Z,1431975165.141 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-05-18T18:52:45.141Z,1431975165.141 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-05-18T18:52:45.142Z,1431975165.142 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-05-18T18:52:59.098Z,1431975179.098 [HFRadarCompactModelForecaster] Loaded
2015-05-18T18:52:59.098Z,1431975179.098 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2015-05-18T18:53:02.690Z,1431975182.690 [HFRCMSpaceInterpolator] Loaded
2015-05-18T18:53:02.690Z,1431975182.690 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2015-05-18T18:53:02.693Z,1431975182.693 [HFRCMTimeInterpolator] Loaded
2015-05-18T18:53:02.693Z,1431975182.693 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2015-05-18T18:53:06.108Z,1431975186.108 [HFRCMReconstructedInterpolator] Loaded
2015-05-18T18:53:06.108Z,1431975186.108 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2015-05-18T18:53:06.148Z,1431975186.148 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2015-05-18T18:53:06.148Z,1431975186.148 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2015-05-18T18:53:06.232Z,1431975186.232 [HFRCMVirtualSurfaceDrifter] Loaded
2015-05-18T18:53:06.232Z,1431975186.232 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2015-05-18T18:53:06.233Z,1431975186.233 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-05-18T18:53:06.246Z,1431975186.246 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-05-18T18:53:07.024Z,1431975187.024 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-05-18T18:53:07.024Z,1431975187.024 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-05-18T18:53:07.367Z,1431975187.367 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-05-18T18:53:07.367Z,1431975187.367 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-05-18T18:53:07.541Z,1431975187.541 [DeadReckonUsingSpeedCalculator] Loaded
2015-05-18T18:53:07.541Z,1431975187.541 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-05-18T18:53:07.595Z,1431975187.595 [NavChart] Loaded
2015-05-18T18:53:07.595Z,1431975187.595 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-05-18T18:53:07.615Z,1431975187.615 [UniversalFixResidualReporter] Loaded
2015-05-18T18:53:07.616Z,1431975187.616 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-05-18T18:53:07.616Z,1431975187.616 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-05-18T18:53:07.617Z,1431975187.617 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-05-18T18:53:07.626Z,1431975187.626 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-05-18T18:53:07.626Z,1431975187.626 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-05-18T18:53:08.032Z,1431975188.032 [Aanderaa_O2] Loaded
2015-05-18T18:53:08.032Z,1431975188.032 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2015-05-18T18:53:08.079Z,1431975188.079 [CANONSampler] Loaded
2015-05-18T18:53:08.080Z,1431975188.080 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2015-05-18T18:53:08.393Z,1431975188.393 [CTD_NeilBrown] Loaded
2015-05-18T18:53:08.394Z,1431975188.394 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-05-18T18:53:08.415Z,1431975188.415 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406EA4E0
2015-05-18T18:53:08.415Z,1431975188.415 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1979
2015-05-18T18:53:08.481Z,1431975188.481 [PAR_Licor] Loaded
2015-05-18T18:53:08.481Z,1431975188.481 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-05-18T18:53:08.678Z,1431975188.678 [WetLabsBB2FL] Loaded
2015-05-18T18:53:08.678Z,1431975188.678 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-05-18T18:53:08.702Z,1431975188.702 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4071A4E0
2015-05-18T18:53:08.702Z,1431975188.702 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1980
2015-05-18T18:53:08.703Z,1431975188.703 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-05-18T18:53:08.703Z,1431975188.703 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-05-18T18:53:10.249Z,1431975190.249 [AcousticModem_Benthos_ATM900] Loaded
2015-05-18T18:53:10.250Z,1431975190.250 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2015-05-18T18:53:11.033Z,1431975191.033 [DataOverHttps] Loaded
2015-05-18T18:53:11.033Z,1431975191.033 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-05-18T18:53:11.059Z,1431975191.059 [Depth_Keller] Loaded
2015-05-18T18:53:11.059Z,1431975191.059 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-05-18T18:53:11.064Z,1431975191.064 [DropWeight] Loaded
2015-05-18T18:53:11.065Z,1431975191.065 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-05-18T18:53:11.290Z,1431975191.290 [NAL9602] Loaded
2015-05-18T18:53:11.290Z,1431975191.290 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-05-18T18:53:11.368Z,1431975191.368 [Onboard] Loaded
2015-05-18T18:53:11.368Z,1431975191.368 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-05-18T18:53:11.388Z,1431975191.388 [Radio_Surface] Loaded
2015-05-18T18:53:11.388Z,1431975191.388 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-05-18T18:53:11.395Z,1431975191.395 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095E4E0
2015-05-18T18:53:11.395Z,1431975191.395 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1981
2015-05-18T18:53:11.871Z,1431975191.871 [PNI_TCM] Loaded
2015-05-18T18:53:11.872Z,1431975191.872 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-05-18T18:53:12.239Z,1431975192.239 [Rowe_600] Loaded
2015-05-18T18:53:12.239Z,1431975192.239 [ComponentRegistry](DEBUG): SyncComponent "Rowe_600" handled in the control thread.
2015-05-18T18:53:16.133Z,1431975196.133 [BPC1] Loaded
2015-05-18T18:53:16.145Z,1431975196.145 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-05-18T18:53:16.146Z,1431975196.146 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-05-18T18:53:16.146Z,1431975196.146 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-05-18T18:53:16.354Z,1431975196.354 [BuoyancyServo] Loaded
2015-05-18T18:53:16.354Z,1431975196.354 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-05-18T18:53:16.379Z,1431975196.379 [ElevatorServo] Loaded
2015-05-18T18:53:16.379Z,1431975196.379 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-05-18T18:53:16.403Z,1431975196.403 [MassServo] Loaded
2015-05-18T18:53:16.404Z,1431975196.404 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-05-18T18:53:16.420Z,1431975196.420 [RudderServo] Loaded
2015-05-18T18:53:16.421Z,1431975196.421 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-05-18T18:53:16.452Z,1431975196.452 [ThrusterServo] Loaded
2015-05-18T18:53:16.453Z,1431975196.453 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-05-18T18:53:16.453Z,1431975196.453 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-05-18T18:53:16.466Z,1431975196.466 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-05-18T18:53:16.687Z,1431975196.687 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-05-18T18:53:16.688Z,1431975196.688 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-05-18T18:53:16.747Z,1431975196.747 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-05-18T18:53:16.763Z,1431975196.763 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-05-18T18:53:16.764Z,1431975196.764 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-05-18T18:53:16.771Z,1431975196.771 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-05-18T18:53:16.778Z,1431975196.778 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A0C4E0
2015-05-18T18:53:16.779Z,1431975196.779 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1982
2015-05-18T18:53:16.784Z,1431975196.784 [Supervisor](INFO): Main Thread ID is 1893
2015-05-18T18:53:16.784Z,1431975196.784 [Supervisor](DEBUG): Running supervisor.
2015-05-18T18:53:16.785Z,1431975196.785 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1983
2015-05-18T18:53:16.792Z,1431975196.792 [controlThread ThreadHandler](INFO): Handler Thread ID is 1984
2015-05-18T18:53:16.793Z,1431975196.793 [controlThread](DEBUG): Initializing ControlThread
2015-05-18T18:53:16.793Z,1431975196.793 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-05-18T18:53:16.795Z,1431975196.795 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-05-18T18:53:16.796Z,1431975196.796 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-05-18T18:53:16.796Z,1431975196.796 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-05-18T18:53:16.797Z,1431975196.797 [SBIT](INFO): Initialize SBIT Component.
2015-05-18T18:53:16.797Z,1431975196.797 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12241
2015-05-18T18:53:16.797Z,1431975196.797 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-05-18T18:53:16.798Z,1431975196.798 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-05-18T18:53:16.798Z,1431975196.798 [IBIT](INFO): Initialize IBIT Component.
2015-05-18T18:53:16.799Z,1431975196.799 [CBIT](DEBUG): Initialize CBIT Component.
2015-05-18T18:53:16.799Z,1431975196.799 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-05-18T18:53:16.799Z,1431975196.799 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-05-18T18:53:16.811Z,1431975196.811 [logger ThreadHandler](INFO): Handler Thread ID is 1985
2015-05-18T18:53:16.822Z,1431975196.822 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-05-18T18:53:16.823Z,1431975196.823 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-05-18T18:53:16.823Z,1431975196.823 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-05-18T18:53:16.823Z,1431975196.823 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-05-18T18:53:16.824Z,1431975196.824 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-05-18T18:53:16.939Z,1431975196.939 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1986
2015-05-18T18:53:16.940Z,1431975196.940 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2015-05-18T18:53:16.942Z,1431975196.942 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1988
2015-05-18T18:53:16.947Z,1431975196.947 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2015-05-18T18:53:16.957Z,1431975196.957 [WetLabsBB2FL](INFO): Powering down
2015-05-18T18:53:16.979Z,1431975196.979 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1989
2015-05-18T18:53:16.985Z,1431975196.985 [Radio_Surface](INFO): Powering up
2015-05-18T18:53:16.999Z,1431975196.999 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1990
2015-05-18T18:53:17.004Z,1431975197.004 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-05-18T18:53:17.005Z,1431975197.005 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-05-18T18:53:17.005Z,1431975197.005 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-05-18T18:53:17.005Z,1431975197.005 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-05-18T18:53:17.006Z,1431975197.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-05-18T18:53:17.006Z,1431975197.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-05-18T18:53:17.007Z,1431975197.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-05-18T18:53:17.007Z,1431975197.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-05-18T18:53:17.007Z,1431975197.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-05-18T18:53:17.010Z,1431975197.010 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2015-05-18T18:53:17.010Z,1431975197.010 [HFRadarCompactModelForecaster](ERROR): Most recent ECs are more than six hours old.
2015-05-18T18:53:17.367Z,1431975197.367 [CTD_NeilBrown](DEBUG): CTD uart timeout: serial timeout
2015-05-18T18:53:17.368Z,1431975197.368 [CTD_NeilBrown](ERROR): Could not get real or simulated CTD data. startTime.elapsed() = 0.420155
2015-05-18T18:53:28.426Z,1431975208.426 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201412101700 published
2015-05-18T18:53:28.441Z,1431975208.441 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2015-05-18T18:53:28.443Z,1431975208.443 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2015-05-18T18:53:28.444Z,1431975208.444 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2015-05-18T18:53:28.457Z,1431975208.457 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2015-05-18T18:53:28.458Z,1431975208.458 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2015-05-18T18:53:28.458Z,1431975208.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-05-18T18:53:28.459Z,1431975208.459 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-05-18T18:53:28.459Z,1431975208.459 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-05-18T18:53:28.459Z,1431975208.459 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-05-18T18:53:28.468Z,1431975208.468 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-05-18T18:53:28.533Z,1431975208.533 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-05-18T18:53:28.566Z,1431975208.566 [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-05-18T18:53:28.566Z,1431975208.566 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-05-18T18:53:28.667Z,1431975208.667 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2015-05-18T18:53:28.668Z,1431975208.668 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-05-18T18:53:28.700Z,1431975208.700 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-05-18T18:53:28.761Z,1431975208.761 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-05-18T18:53:28.764Z,1431975208.764 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-05-18T18:53:28.798Z,1431975208.798 [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>

    <Aggregate Id="CheckIn">

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

<!-- Run application at high speed -->

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

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

<!-- Do data comms -->

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

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

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2015-05-18T18:53:28.818Z,1431975208.818 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Rowe_600,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-05-18T18:53:28.889Z,1431975208.889 [CANONSampler](INFO): Powering down
2015-05-18T18:53:29.023Z,1431975209.023 [AcousticModem_Benthos_ATM900](INFO): Powering up
2015-05-18T18:53:29.023Z,1431975209.023 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2015-05-18T18:53:29.203Z,1431975209.203 [NAL9602](INFO): Powering up NAL9602
2015-05-18T18:53:29.303Z,1431975209.303 [Rowe_600](INFO): Initializing
2015-05-18T18:53:29.314Z,1431975209.314 [Rowe_600](INFO): Powering up
2015-05-18T18:53:53.382Z,1431975233.382 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 3.140000 seconds.
2015-05-18T18:53:53.383Z,1431975233.383 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2015-05-18T18:53:53.383Z,1431975233.383 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-05-18T18:53:53.911Z,1431975233.911 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 3.140000 seconds.
2015-05-18T18:53:53.911Z,1431975233.911 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2015-05-18T18:53:53.911Z,1431975233.911 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-05-18T18:53:54.132Z,1431975234.132 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-05-18T18:53:54.144Z,1431975234.144 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-05-18T18:53:54.163Z,1431975234.163 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-05-18T18:53:54.173Z,1431975234.173 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-05-18T18:53:54.188Z,1431975234.188 [MassServo](DEBUG): Initializing EZServoServo.
2015-05-18T18:53:54.216Z,1431975234.216 [MassServo](DEBUG): Initializing MassServo.
2015-05-18T18:53:54.241Z,1431975234.241 [RudderServo](DEBUG): Initializing EZServoServo.
2015-05-18T18:53:54.253Z,1431975234.253 [RudderServo](DEBUG): Initializing RudderServo.
2015-05-18T18:53:54.304Z,1431975234.304 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-05-18T18:53:54.338Z,1431975234.338 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-05-18T18:53:54.776Z,1431975234.776 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-05-18T18:53:54.792Z,1431975234.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-05-18T18:53:55.429Z,1431975235.429 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2015-05-18T18:53:55.429Z,1431975235.429 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2015-05-18T18:53:55.429Z,1431975235.429 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2015-05-18T18:53:56.494Z,1431975236.494 [SBIT](IMPORTANT): Beginning Startup BIT
2015-05-18T18:53:56.508Z,1431975236.508 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-05-18T18:53:56.509Z,1431975236.509 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2015-05-18T18:53:56.524Z,1431975236.524 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-05-18T18:53:56.525Z,1431975236.525 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2015-05-18T18:53:56.533Z,1431975236.533 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2015-05-18T18:53:56.535Z,1431975236.535 [CBIT](IMPORTANT): Beginning GF scan
2015-05-18T18:53:56.618Z,1431975236.618 [AcousticModem_Benthos_ATM900](INFO): Powering down
2015-05-18T18:53:56.879Z,1431975236.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-05-18T18:53:56.880Z,1431975236.880 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-05-18T18:53:57.085Z,1431975237.085 [Aanderaa_O2](INFO): Powering down
2015-05-18T18:53:57.155Z,1431975237.155 [NAL9602](INFO): NAL9602 initialized
2015-05-18T18:53:57.233Z,1431975237.233 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 3.140000 seconds.
2015-05-18T18:53:57.233Z,1431975237.233 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2015-05-18T18:53:57.233Z,1431975237.233 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-05-18T18:53:57.236Z,1431975237.236 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 3.140000 seconds.
2015-05-18T18:53:57.236Z,1431975237.236 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2015-05-18T18:53:57.236Z,1431975237.236 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-05-18T18:53:57.272Z,1431975237.272 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-05-18T18:53:57.272Z,1431975237.272 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-05-18T18:53:57.542Z,1431975237.542 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-05-18T18:53:57.542Z,1431975237.542 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2015-05-18T18:53:57.543Z,1431975237.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-05-18T18:53:57.543Z,1431975237.543 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2015-05-18T18:53:57.543Z,1431975237.543 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2015-05-18T18:53:57.543Z,1431975237.543 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2015-05-18T18:53:57.851Z,1431975237.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-05-18T18:53:57.851Z,1431975237.851 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-05-18T18:53:58.268Z,1431975238.268 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 3.140000 seconds.
2015-05-18T18:53:58.268Z,1431975238.268 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2015-05-18T18:53:58.268Z,1431975238.268 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-05-18T18:53:58.270Z,1431975238.270 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 3.140000 seconds.
2015-05-18T18:53:58.283Z,1431975238.283 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2015-05-18T18:53:58.283Z,1431975238.283 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-05-18T18:53:58.332Z,1431975238.332 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-05-18T18:53:58.332Z,1431975238.332 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-05-18T18:53:58.939Z,1431975238.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-05-18T18:53:58.939Z,1431975238.939 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2015-05-18T18:53:58.940Z,1431975238.940 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-05-18T18:53:58.940Z,1431975238.940 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2015-05-18T18:53:59.201Z,1431975239.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-05-18T18:53:59.202Z,1431975239.202 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-05-18T18:53:59.766Z,1431975239.766 [AcousticModem_Benthos_ATM900](INFO): Powering up
2015-05-18T18:53:59.766Z,1431975239.766 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2015-05-18T18:54:06.486Z,1431975246.486 [Rowe_600](DEBUG): 4063 bytes available, but need 4084
2015-05-18T18:54:07.073Z,1431975247.073 [AcousticModem_Benthos_ATM900](DEBUG): 
2015-05-18T18:54:07.628Z,1431975247.628 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:07.628Z,1431975247.628 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:08.541Z,1431975248.541 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2015-05-18T18:54:08.541Z,1431975248.541 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2015-05-18T18:54:08.542Z,1431975248.542 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2015-05-18T18:54:08.635Z,1431975248.635 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:08.635Z,1431975248.635 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:09.012Z,1431975249.012 [AcousticModem_Benthos_ATM900](DEBUG): May 18 2015 18:57:27 
2015-05-18T18:54:09.336Z,1431975249.336 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:09.336Z,1431975249.336 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:09.554Z,1431975249.554 [SBIT](ERROR): Could not read massPosReader_.
2015-05-18T18:54:09.683Z,1431975249.683 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:09.683Z,1431975249.683 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:10.119Z,1431975250.119 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2015-05-18T18:54:10.443Z,1431975250.443 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:10.445Z,1431975250.445 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:11.132Z,1431975251.132 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:11.350Z,1431975251.350 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:11.350Z,1431975251.350 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:12.091Z,1431975252.091 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:12.224Z,1431975252.224 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:12.236Z,1431975252.236 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:12.456Z,1431975252.456 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:12.551Z,1431975252.551 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:12.552Z,1431975252.552 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:12.972Z,1431975252.972 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:13.086Z,1431975253.086 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:13.086Z,1431975253.086 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:13.323Z,1431975253.323 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:13.419Z,1431975253.419 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:13.419Z,1431975253.419 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:13.820Z,1431975253.820 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:14.164Z,1431975254.164 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:54:14.165Z,1431975254.165 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:54:14.921Z,1431975254.921 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:15.306Z,1431975255.306 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:54:15.307Z,1431975255.307 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:54:15.867Z,1431975255.867 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:15.964Z,1431975255.964 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:15.964Z,1431975255.964 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:16.316Z,1431975256.316 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:16.634Z,1431975256.634 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:16.635Z,1431975256.635 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:16.969Z,1431975256.969 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:17.081Z,1431975257.081 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:17.081Z,1431975257.081 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:17.398Z,1431975257.398 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:17.719Z,1431975257.719 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:54:17.720Z,1431975257.720 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:54:18.420Z,1431975258.420 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:18.761Z,1431975258.761 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:18.762Z,1431975258.762 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:19.426Z,1431975259.426 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:19.622Z,1431975259.622 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:19.622Z,1431975259.622 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:19.817Z,1431975259.817 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2015-05-18T18:54:19.817Z,1431975259.817 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2015-05-18T18:54:19.817Z,1431975259.817 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2015-05-18T18:54:19.899Z,1431975259.899 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:19.900Z,1431975259.900 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:20.057Z,1431975260.057 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2015-05-18T18:54:20.255Z,1431975260.255 [AcousticModem_Benthos_ATM900](INFO): Powering down
2015-05-18T18:54:20.501Z,1431975260.501 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:20.503Z,1431975260.503 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:20.745Z,1431975260.745 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:20.745Z,1431975260.745 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:21.322Z,1431975261.322 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:21.336Z,1431975261.336 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:21.845Z,1431975261.845 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2015-05-18T18:54:21.846Z,1431975261.846 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2015-05-18T18:54:22.282Z,1431975262.282 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:22.283Z,1431975262.283 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:23.035Z,1431975263.035 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:23.036Z,1431975263.036 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:23.468Z,1431975263.468 [AcousticModem_Benthos_ATM900](INFO): Powering up
2015-05-18T18:54:23.469Z,1431975263.469 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2015-05-18T18:54:23.573Z,1431975263.573 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:23.573Z,1431975263.573 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:23.721Z,1431975263.721 [CBIT](IMPORTANT): No ground fault detected
2015-05-18T18:54:24.175Z,1431975264.175 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:24.175Z,1431975264.175 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:24.489Z,1431975264.489 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:24.490Z,1431975264.490 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:25.126Z,1431975265.126 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:25.127Z,1431975265.127 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:26.084Z,1431975266.084 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:26.085Z,1431975266.085 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:26.818Z,1431975266.818 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:26.819Z,1431975266.819 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:27.254Z,1431975267.254 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:27.254Z,1431975267.254 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:27.868Z,1431975267.868 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:27.868Z,1431975267.868 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:28.148Z,1431975268.148 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:28.148Z,1431975268.148 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:28.724Z,1431975268.724 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:28.725Z,1431975268.725 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:29.709Z,1431975269.709 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:29.710Z,1431975269.710 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:30.498Z,1431975270.498 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:30.499Z,1431975270.499 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:30.846Z,1431975270.846 [AcousticModem_Benthos_ATM900](DEBUG): 
2015-05-18T18:54:31.064Z,1431975271.064 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:31.065Z,1431975271.065 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:31.597Z,1431975271.597 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:31.597Z,1431975271.597 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:31.873Z,1431975271.873 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:31.873Z,1431975271.873 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:32.245Z,1431975272.245 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2015-05-18T18:54:32.261Z,1431975272.261 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2015-05-18T18:54:32.276Z,1431975272.276 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2015-05-18T18:54:32.531Z,1431975272.531 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:32.545Z,1431975272.545 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:33.183Z,1431975273.183 [AcousticModem_Benthos_ATM900](DEBUG): May 18 2015 18:57:51 
2015-05-18T18:54:33.531Z,1431975273.531 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:33.532Z,1431975273.532 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:34.404Z,1431975274.404 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:34.404Z,1431975274.404 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:34.597Z,1431975274.597 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2015-05-18T18:54:34.711Z,1431975274.711 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:34.711Z,1431975274.711 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:35.041Z,1431975275.041 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-05-18T18:54:35.056Z,1431975275.056 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2015-05-18T18:54:35.057Z,1431975275.057 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2015-05-18T18:54:35.299Z,1431975275.299 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:35.299Z,1431975275.299 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:35.486Z,1431975275.486 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2015-05-18T18:54:35.591Z,1431975275.591 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:35.591Z,1431975275.591 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:35.920Z,1431975275.920 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2015-05-18T18:54:35.937Z,1431975275.937 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2015-05-18T18:54:36.231Z,1431975276.231 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:36.233Z,1431975276.233 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:36.875Z,1431975276.875 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2015-05-18T18:54:37.269Z,1431975277.269 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:37.271Z,1431975277.271 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:38.123Z,1431975278.123 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:38.124Z,1431975278.124 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:38.428Z,1431975278.428 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:38.428Z,1431975278.428 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:39.009Z,1431975279.009 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:39.009Z,1431975279.009 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:39.349Z,1431975279.349 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:39.349Z,1431975279.349 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:40.603Z,1431975280.603 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:40.604Z,1431975280.604 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:41.547Z,1431975281.547 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:41.548Z,1431975281.548 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:42.134Z,1431975282.134 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:42.134Z,1431975282.134 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:42.725Z,1431975282.725 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:42.725Z,1431975282.725 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:43.051Z,1431975283.051 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:43.051Z,1431975283.051 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:43.858Z,1431975283.858 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:43.860Z,1431975283.860 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:44.846Z,1431975284.846 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:44.860Z,1431975284.860 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:45.533Z,1431975285.533 [CommandLine](IMPORTANT): got command burn 5.000000
2015-05-18T18:54:45.626Z,1431975285.626 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:45.627Z,1431975285.627 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:46.019Z,1431975286.019 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:46.019Z,1431975286.019 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:46.971Z,1431975286.971 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:54:46.972Z,1431975286.972 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:54:47.877Z,1431975287.877 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:54:47.879Z,1431975287.879 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:54:48.815Z,1431975288.815 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:48.815Z,1431975288.815 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:49.336Z,1431975289.336 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:54:49.337Z,1431975289.337 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:54:49.654Z,1431975289.654 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:49.666Z,1431975289.666 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:50.177Z,1431975290.177 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:50.177Z,1431975290.177 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:50.451Z,1431975290.451 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:50.451Z,1431975290.451 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:54:50.564Z,1431975290.564 [CommandLine](IMPORTANT): Deactivating dropweight wire
2015-05-18T18:54:51.276Z,1431975291.276 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:54:51.290Z,1431975291.290 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:55:31.841Z,1431975331.841 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:55:31.841Z,1431975331.841 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:55:32.213Z,1431975332.213 [BPC1](ERROR): Failed to receive battery data
2015-05-18T18:55:32.213Z,1431975332.213 [BPC1] Communications Fault, FailCount= 1
2015-05-18T18:55:32.214Z,1431975332.214 [BPC1](ERROR): Communications Fault
2015-05-18T18:55:32.739Z,1431975332.739 [SBIT](CRITICAL): SBIT FAILED
2015-05-18T18:55:32.778Z,1431975332.778 [CommandLine](FAULT): Scheduling is paused
2015-05-18T18:55:32.789Z,1431975332.789 [CBIT](ERROR): Communications Fault in component: BPC1
2015-05-18T18:55:33.232Z,1431975333.232 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:55:33.233Z,1431975333.233 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:55:33.635Z,1431975333.635 [MissionManager](IMPORTANT): Started mission Startup
2015-05-18T18:55:33.635Z,1431975333.635 [Startup] Running Loop=1
2015-05-18T18:55:33.655Z,1431975333.655 [Startup](INFO): Aggregate::initialize Startup
2015-05-18T18:55:33.655Z,1431975333.655 [Startup:A.GoToSurface] Running Loop=1
2015-05-18T18:55:33.655Z,1431975333.655 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-05-18T18:55:33.656Z,1431975333.656 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-05-18T18:55:33.657Z,1431975333.657 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-05-18T18:55:33.657Z,1431975333.657 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-05-18T18:55:33.697Z,1431975333.697 [Startup:StartupSatComms] Running Loop=1
2015-05-18T18:55:33.697Z,1431975333.697 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2015-05-18T18:55:33.697Z,1431975333.697 [Startup:StartupSatComms:A] Running Loop=1
2015-05-18T18:55:34.076Z,1431975334.076 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:55:34.078Z,1431975334.078 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:55:34.351Z,1431975334.351 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-05-18T18:56:05.759Z,1431975365.759 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:05.760Z,1431975365.760 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:06.370Z,1431975366.370 [CBIT](INFO): Clearing failed state for component BPC1
2015-05-18T18:56:06.370Z,1431975366.370 [BPC1] No Fault, FailCount= 1
2015-05-18T18:56:06.814Z,1431975366.814 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:06.835Z,1431975366.835 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:07.806Z,1431975367.806 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:07.807Z,1431975367.807 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:08.190Z,1431975368.190 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:08.191Z,1431975368.191 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:09.628Z,1431975369.628 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:09.630Z,1431975369.630 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:10.527Z,1431975370.527 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:56:10.527Z,1431975370.527 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:56:10.784Z,1431975370.784 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:10.784Z,1431975370.784 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:11.444Z,1431975371.444 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:11.444Z,1431975371.444 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:11.793Z,1431975371.793 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:11.793Z,1431975371.793 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:12.643Z,1431975372.643 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:12.643Z,1431975372.643 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:13.811Z,1431975373.811 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:13.811Z,1431975373.811 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:14.413Z,1431975374.413 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:14.413Z,1431975374.413 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:15.061Z,1431975375.061 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:15.062Z,1431975375.062 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:15.422Z,1431975375.422 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:15.422Z,1431975375.422 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:17.597Z,1431975377.597 [Rowe_600](ERROR): first preamble found after 6406 bytes, which is more than sizeof( deviceResponse_ ) 4116
2015-05-18T18:56:17.658Z,1431975377.658 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:17.676Z,1431975377.676 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:18.228Z,1431975378.228 [Rowe_600](ERROR): Ensemble number mismatch: 169 in header, 228 in ensemble data matrix.
2015-05-18T18:56:18.244Z,1431975378.244 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4196bc]
2015-05-18T18:56:18.245Z,1431975378.245 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41970c]
2015-05-18T18:56:19.182Z,1431975379.182 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195bc]
2015-05-18T18:56:19.183Z,1431975379.183 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41960c]
2015-05-18T18:56:20.049Z,1431975380.049 [Rowe_600](ERROR): first preamble found after 5877 bytes, which is more than sizeof( deviceResponse_ ) 4116
2015-05-18T18:56:21.624Z,1431975381.624 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:21.646Z,1431975381.646 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:21.937Z,1431975381.937 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:21.937Z,1431975381.937 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:22.661Z,1431975382.661 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:22.661Z,1431975382.661 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:24.002Z,1431975384.002 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:24.003Z,1431975384.003 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:24.847Z,1431975384.847 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:56:24.848Z,1431975384.848 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:56:25.382Z,1431975385.382 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:25.383Z,1431975385.383 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:26.010Z,1431975386.010 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-05-18T18:56:26.029Z,1431975386.029 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:26.029Z,1431975386.029 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:26.312Z,1431975386.312 [Rowe_600](DEBUG): LCB error: Software Overcurrent.
2015-05-18T18:56:26.330Z,1431975386.330 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:26.330Z,1431975386.330 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:27.157Z,1431975387.157 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:27.203Z,1431975387.203 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:28.335Z,1431975388.335 [Rowe_600](ERROR): Ensemble number mismatch: 249 in header, 251 in ensemble data matrix.
2015-05-18T18:56:28.350Z,1431975388.350 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x419684]
2015-05-18T18:56:28.351Z,1431975388.351 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x4196d4]
2015-05-18T18:56:28.984Z,1431975388.984 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195b4]
2015-05-18T18:56:28.984Z,1431975388.984 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419604]
2015-05-18T18:56:29.726Z,1431975389.726 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:29.731Z,1431975389.731 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:30.064Z,1431975390.064 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:30.065Z,1431975390.065 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:30.746Z,1431975390.746 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:30.747Z,1431975390.747 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:32.321Z,1431975392.321 [Rowe_600](ERROR): Ensemble number mismatch: 257 in header, 258 in ensemble data matrix.
2015-05-18T18:56:32.341Z,1431975392.341 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x419273]
2015-05-18T18:56:32.368Z,1431975392.368 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x4192c3]
2015-05-18T18:56:32.903Z,1431975392.903 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:32.904Z,1431975392.904 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:33.524Z,1431975393.524 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:33.525Z,1431975393.525 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:33.835Z,1431975393.835 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:56:33.835Z,1431975393.835 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:56:34.064Z,1431975394.064 [Startup:StartupSatComms:A](INFO): Timed out from 2015-05-18T18:55:33.7Z
2015-05-18T18:56:34.073Z,1431975394.073 [Startup:StartupSatComms:A] Stopped
2015-05-18T18:56:34.074Z,1431975394.074 [Startup:StartupSatComms:B] Running Loop=1
2015-05-18T18:57:06.486Z,1431975426.486 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:57:06.516Z,1431975426.516 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:57:06.620Z,1431975426.620 [BPC1](ERROR): Failed to receive battery data
2015-05-18T18:57:06.620Z,1431975426.620 [BPC1] Communications Fault, FailCount= 1
2015-05-18T18:57:06.620Z,1431975426.620 [BPC1](ERROR): Communications Fault
2015-05-18T18:57:06.655Z,1431975426.655 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-05-18T18:57:06.655Z,1431975426.655 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2015-05-18T18:57:06.655Z,1431975426.655 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-05-18T18:57:06.674Z,1431975426.674 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-05-18T18:57:06.674Z,1431975426.674 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2015-05-18T18:57:06.674Z,1431975426.674 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-05-18T18:57:06.677Z,1431975426.677 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-05-18T18:57:06.745Z,1431975426.745 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-05-18T18:57:06.745Z,1431975426.745 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-05-18T18:57:06.746Z,1431975426.746 [CBIT](ERROR): Communications Fault in component: BPC1
2015-05-18T18:57:10.343Z,1431975430.343 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:10.343Z,1431975430.343 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:10.435Z,1431975430.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-05-18T18:57:10.435Z,1431975430.435 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2015-05-18T18:57:10.435Z,1431975430.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-05-18T18:57:10.435Z,1431975430.435 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2015-05-18T18:57:10.600Z,1431975430.600 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:10.600Z,1431975430.600 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:10.662Z,1431975430.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-05-18T18:57:10.663Z,1431975430.663 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-05-18T18:57:11.280Z,1431975431.280 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:11.281Z,1431975431.281 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:12.291Z,1431975432.291 [CBIT](INFO): Clearing failed state for component BPC1
2015-05-18T18:57:12.291Z,1431975432.291 [BPC1] No Fault, FailCount= 1
2015-05-18T18:57:12.770Z,1431975432.770 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:12.771Z,1431975432.771 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:13.517Z,1431975433.517 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:13.517Z,1431975433.517 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:14.106Z,1431975434.106 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:14.106Z,1431975434.106 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:14.502Z,1431975434.502 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x418d27]
2015-05-18T18:57:14.502Z,1431975434.502 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x418d77]
2015-05-18T18:57:16.003Z,1431975436.003 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195bc]
2015-05-18T18:57:16.004Z,1431975436.004 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41960c]
2015-05-18T18:57:16.909Z,1431975436.909 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:16.909Z,1431975436.909 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:17.163Z,1431975437.163 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:17.163Z,1431975437.163 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:17.771Z,1431975437.771 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:17.771Z,1431975437.771 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:18.136Z,1431975438.136 [Rowe_600](ERROR): Ensemble number mismatch: 278 in header, 279 in ensemble data matrix.
2015-05-18T18:57:18.137Z,1431975438.137 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4193ae]
2015-05-18T18:57:18.137Z,1431975438.137 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x4193fe]
2015-05-18T18:57:18.977Z,1431975438.977 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:18.978Z,1431975438.978 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:20.201Z,1431975440.201 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:20.201Z,1431975440.201 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:20.767Z,1431975440.767 [Rowe_600](ERROR): Ensemble number mismatch: 283 in header, 343 in ensemble data matrix.
2015-05-18T18:57:20.767Z,1431975440.767 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x418f33]
2015-05-18T18:57:20.767Z,1431975440.767 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x418f83]
2015-05-18T18:57:21.765Z,1431975441.765 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:21.765Z,1431975441.765 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:22.694Z,1431975442.694 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:22.694Z,1431975442.694 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:23.967Z,1431975443.967 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:23.968Z,1431975443.968 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:24.564Z,1431975444.564 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:24.564Z,1431975444.564 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:25.183Z,1431975445.183 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:25.183Z,1431975445.183 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:26.395Z,1431975446.395 [Rowe_600](DEBUG): 3679 bytes available, but need 4084
2015-05-18T18:57:27.574Z,1431975447.574 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:27.574Z,1431975447.574 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:28.115Z,1431975448.115 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:28.116Z,1431975448.116 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:28.791Z,1431975448.791 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:28.792Z,1431975448.792 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:29.175Z,1431975449.175 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:29.175Z,1431975449.175 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:29.729Z,1431975449.729 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:29.730Z,1431975449.730 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:31.329Z,1431975451.329 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:31.329Z,1431975451.329 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:31.946Z,1431975451.946 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:57:31.946Z,1431975451.946 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:57:32.583Z,1431975452.583 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:32.583Z,1431975452.583 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:32.867Z,1431975452.867 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:32.867Z,1431975452.867 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:33.752Z,1431975453.752 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:33.753Z,1431975453.753 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:34.893Z,1431975454.893 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:34.894Z,1431975454.894 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:35.035Z,1431975455.035 [Startup:StartupSatComms:B](INFO): Timed out from 2015-05-18T18:56:34.1Z
2015-05-18T18:57:35.053Z,1431975455.053 [Startup:StartupSatComms:B] Stopped
2015-05-18T18:57:35.072Z,1431975455.072 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-05-18T18:57:35.073Z,1431975455.073 [Startup:StartupSatComms] Stopped
2015-05-18T18:57:35.074Z,1431975455.074 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2015-05-18T18:57:35.112Z,1431975455.112 [Startup](INFO): Completed Startup
2015-05-18T18:57:35.114Z,1431975455.114 [Startup] Stopped
2015-05-18T18:57:35.114Z,1431975455.114 [Startup](INFO): Aggregate::uninitialize Startup
2015-05-18T18:57:35.115Z,1431975455.115 [Startup:A.GoToSurface] Stopped
2015-05-18T18:57:35.115Z,1431975455.115 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-05-18T18:57:35.523Z,1431975455.523 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:57:35.523Z,1431975455.523 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:57:35.604Z,1431975455.604 [MissionManager](IMPORTANT): Started mission Default
2015-05-18T18:57:35.604Z,1431975455.604 [Default] Running Loop=1
2015-05-18T18:57:35.604Z,1431975455.604 [Default](INFO): Aggregate::initialize Default
2015-05-18T18:57:35.604Z,1431975455.604 [Default:A.GoToSurface] Running Loop=1
2015-05-18T18:57:35.604Z,1431975455.604 [Default:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-05-18T18:57:35.621Z,1431975455.621 [Default:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-05-18T18:57:35.621Z,1431975455.621 [Default:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-05-18T18:57:35.622Z,1431975455.622 [Default:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-05-18T18:57:35.622Z,1431975455.622 [Default:CheckIn] Running Loop=1
2015-05-18T18:57:35.622Z,1431975455.622 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-05-18T18:57:35.622Z,1431975455.622 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-05-18T18:57:35.622Z,1431975455.622 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-05-18T18:57:35.623Z,1431975455.623 [Default:CheckIn:Read_GPS] Running Loop=1
2015-05-18T18:57:35.623Z,1431975455.623 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2015-05-18T18:57:35.634Z,1431975455.634 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-05-18T18:57:35.686Z,1431975455.686 [Default:WaitAtTheSurface] Running Loop=1
2015-05-18T18:57:35.686Z,1431975455.686 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2015-05-18T18:57:35.686Z,1431975455.686 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-05-18T18:57:35.686Z,1431975455.686 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2015-05-18T18:58:09.138Z,1431975489.138 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:58:09.138Z,1431975489.138 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:58:09.188Z,1431975489.188 [BPC1](ERROR): Failed to receive battery data
2015-05-18T18:58:09.188Z,1431975489.188 [BPC1] Communications Fault, FailCount= 1
2015-05-18T18:58:09.188Z,1431975489.188 [BPC1](ERROR): Communications Fault
2015-05-18T18:58:09.619Z,1431975489.619 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-05-18T18:58:09.687Z,1431975489.687 [CBIT](ERROR): Communications Fault in component: BPC1
2015-05-18T18:58:09.830Z,1431975489.830 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:09.846Z,1431975489.846 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:12.721Z,1431975492.721 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:12.721Z,1431975492.721 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:12.907Z,1431975492.907 [CBIT](INFO): Clearing failed state for component BPC1
2015-05-18T18:58:12.907Z,1431975492.907 [BPC1] No Fault, FailCount= 1
2015-05-18T18:58:13.338Z,1431975493.338 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:13.338Z,1431975493.338 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:14.152Z,1431975494.152 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:14.153Z,1431975494.153 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:15.210Z,1431975495.210 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:15.228Z,1431975495.228 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:16.168Z,1431975496.168 [Rowe_600](ERROR): Ensemble number mismatch: 390 in header, 391 in ensemble data matrix.
2015-05-18T18:58:16.454Z,1431975496.454 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:16.455Z,1431975496.455 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:17.056Z,1431975497.056 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:17.056Z,1431975497.056 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:17.329Z,1431975497.329 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:17.329Z,1431975497.329 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:18.274Z,1431975498.274 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:18.274Z,1431975498.274 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:19.674Z,1431975499.674 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:19.675Z,1431975499.675 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:20.136Z,1431975500.136 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:20.136Z,1431975500.136 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:20.762Z,1431975500.762 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:20.775Z,1431975500.775 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:21.070Z,1431975501.070 [Rowe_600](ERROR): first preamble found after 7505 bytes, which is more than sizeof( deviceResponse_ ) 4116
2015-05-18T18:58:21.071Z,1431975501.071 [Rowe_600](DEBUG): 820 bytes available, but need 4084
2015-05-18T18:58:22.344Z,1431975502.344 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:22.345Z,1431975502.345 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:23.452Z,1431975503.452 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:23.453Z,1431975503.453 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:23.848Z,1431975503.848 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:23.849Z,1431975503.849 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:25.577Z,1431975505.577 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:25.603Z,1431975505.603 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:26.875Z,1431975506.875 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:26.875Z,1431975506.875 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:27.489Z,1431975507.489 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:27.506Z,1431975507.506 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:28.135Z,1431975508.135 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:28.136Z,1431975508.136 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:28.441Z,1431975508.441 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:28.441Z,1431975508.441 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:29.176Z,1431975509.176 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:29.178Z,1431975509.178 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:30.603Z,1431975510.603 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:30.604Z,1431975510.604 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:31.348Z,1431975511.348 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:31.348Z,1431975511.348 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:31.949Z,1431975511.949 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:31.949Z,1431975511.949 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:32.251Z,1431975512.251 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:32.251Z,1431975512.251 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:33.108Z,1431975513.108 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:33.108Z,1431975513.108 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:34.694Z,1431975514.694 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:58:34.695Z,1431975514.695 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:58:35.023Z,1431975515.023 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x418dcc]
2015-05-18T18:58:35.023Z,1431975515.023 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x418e1c]
2015-05-18T18:59:07.129Z,1431975547.129 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2015-05-18T18:59:07.485Z,1431975547.485 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:07.486Z,1431975547.486 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:07.656Z,1431975547.656 [BPC1](ERROR): Failed to receive battery data
2015-05-18T18:59:07.656Z,1431975547.656 [BPC1] Communications Fault, FailCount= 1
2015-05-18T18:59:07.656Z,1431975547.656 [BPC1](ERROR): Communications Fault
2015-05-18T18:59:07.707Z,1431975547.707 [CBIT](ERROR): Communications Fault in component: BPC1
2015-05-18T18:59:08.154Z,1431975548.154 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x419500]
2015-05-18T18:59:08.155Z,1431975548.155 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419550]
2015-05-18T18:59:08.434Z,1431975548.434 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:08.434Z,1431975548.434 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:08.986Z,1431975548.986 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:08.986Z,1431975548.986 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:09.124Z,1431975549.124 [CBIT](INFO): Clearing failed state for component BPC1
2015-05-18T18:59:09.125Z,1431975549.125 [BPC1] No Fault, FailCount= 1
2015-05-18T18:59:09.253Z,1431975549.253 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:09.253Z,1431975549.253 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:10.534Z,1431975550.534 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:10.536Z,1431975550.536 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:11.865Z,1431975551.865 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:11.865Z,1431975551.865 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:12.094Z,1431975552.094 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:12.094Z,1431975552.094 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:12.694Z,1431975552.694 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x419523]
2015-05-18T18:59:12.695Z,1431975552.695 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419573]
2015-05-18T18:59:13.740Z,1431975553.740 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:13.745Z,1431975553.745 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:14.925Z,1431975554.925 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:14.926Z,1431975554.926 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:15.647Z,1431975555.647 [Rowe_600](ERROR): Ensemble number mismatch: 508 in header, 511 in ensemble data matrix.
2015-05-18T18:59:16.102Z,1431975556.102 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:16.104Z,1431975556.104 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:16.553Z,1431975556.553 [Rowe_600](ERROR): Ensemble number mismatch: 513 in header, 572 in ensemble data matrix.
2015-05-18T18:59:16.566Z,1431975556.566 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x418f9f]
2015-05-18T18:59:16.566Z,1431975556.566 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x418fef]
2015-05-18T18:59:17.229Z,1431975557.229 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:17.229Z,1431975557.229 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:18.418Z,1431975558.418 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:18.418Z,1431975558.418 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:19.190Z,1431975559.190 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:19.192Z,1431975559.192 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:19.511Z,1431975559.511 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:19.511Z,1431975559.511 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:20.167Z,1431975560.167 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:20.180Z,1431975560.180 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:20.399Z,1431975560.399 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:20.399Z,1431975560.399 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:21.293Z,1431975561.293 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:21.307Z,1431975561.307 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:22.401Z,1431975562.401 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:22.402Z,1431975562.402 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:23.007Z,1431975563.007 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:23.007Z,1431975563.007 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:23.363Z,1431975563.363 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:23.364Z,1431975563.364 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:23.948Z,1431975563.948 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:23.948Z,1431975563.948 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:24.535Z,1431975564.535 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:24.536Z,1431975564.536 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:25.434Z,1431975565.434 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:25.434Z,1431975565.434 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:26.535Z,1431975566.535 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:26.536Z,1431975566.536 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:26.942Z,1431975566.942 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:26.943Z,1431975566.943 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:27.624Z,1431975567.624 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:27.624Z,1431975567.624 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:27.863Z,1431975567.863 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:27.863Z,1431975567.863 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:28.746Z,1431975568.746 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:28.746Z,1431975568.746 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:29.542Z,1431975569.542 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:29.542Z,1431975569.542 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:30.370Z,1431975570.370 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:30.371Z,1431975570.371 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:30.624Z,1431975570.624 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T18:59:30.625Z,1431975570.625 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T18:59:31.243Z,1431975571.243 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:31.243Z,1431975571.243 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:31.516Z,1431975571.516 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:31.517Z,1431975571.517 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:32.236Z,1431975572.236 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:32.237Z,1431975572.237 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:33.517Z,1431975573.517 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:33.518Z,1431975573.518 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:34.270Z,1431975574.270 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:34.271Z,1431975574.271 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:34.945Z,1431975574.945 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T18:59:34.945Z,1431975574.945 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T18:59:35.248Z,1431975575.248 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T18:59:35.249Z,1431975575.249 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:00:01.448Z,1431975601.448 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:01.449Z,1431975601.449 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:01.678Z,1431975601.678 [BPC1](ERROR): Failed to receive battery data
2015-05-18T19:00:01.678Z,1431975601.678 [BPC1] Communications Fault, FailCount= 1
2015-05-18T19:00:01.679Z,1431975601.679 [BPC1](ERROR): Communications Fault
2015-05-18T19:00:02.226Z,1431975602.226 [CBIT](ERROR): Communications Fault in component: BPC1
2015-05-18T19:00:04.018Z,1431975604.018 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:04.018Z,1431975604.018 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:04.636Z,1431975604.636 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002700
2015-05-18T19:00:04.752Z,1431975604.752 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:04.752Z,1431975604.752 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:04.924Z,1431975604.924 [CBIT](INFO): Clearing failed state for component BPC1
2015-05-18T19:00:04.924Z,1431975604.924 [BPC1] No Fault, FailCount= 1
2015-05-18T19:00:05.089Z,1431975605.089 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:05.090Z,1431975605.090 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:07.011Z,1431975607.011 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:07.011Z,1431975607.011 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:08.125Z,1431975608.125 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:08.139Z,1431975608.139 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:08.522Z,1431975608.522 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T19:00:08.522Z,1431975608.522 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T19:00:09.186Z,1431975609.186 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:09.186Z,1431975609.186 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:10.340Z,1431975610.340 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:10.340Z,1431975610.340 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:11.140Z,1431975611.140 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:11.141Z,1431975611.141 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:11.218Z,1431975611.218 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-05-18T19:00:11.231Z,1431975611.231 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2015-05-18T19:00:11.231Z,1431975611.231 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-05-18T19:00:11.233Z,1431975611.233 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-05-18T19:00:11.233Z,1431975611.233 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2015-05-18T19:00:11.233Z,1431975611.233 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-05-18T19:00:11.300Z,1431975611.300 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-05-18T19:00:11.300Z,1431975611.300 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-05-18T19:00:11.430Z,1431975611.430 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:11.430Z,1431975611.430 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:11.576Z,1431975611.576 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-05-18T19:00:11.576Z,1431975611.576 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2015-05-18T19:00:11.577Z,1431975611.577 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-05-18T19:00:11.577Z,1431975611.577 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2015-05-18T19:00:12.077Z,1431975612.077 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:12.077Z,1431975612.077 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:12.148Z,1431975612.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-05-18T19:00:12.165Z,1431975612.165 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-05-18T19:00:12.355Z,1431975612.355 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:12.355Z,1431975612.355 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:12.422Z,1431975612.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1.204676 seconds since last successful read, accepting data for 3.140000 seconds.
2015-05-18T19:00:13.297Z,1431975613.297 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:13.311Z,1431975613.311 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:13.657Z,1431975613.657 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2.439095 seconds since last successful read, accepting data for 3.140000 seconds.
2015-05-18T19:00:14.453Z,1431975614.453 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:14.453Z,1431975614.453 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:15.039Z,1431975615.039 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:15.039Z,1431975615.039 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:15.577Z,1431975615.577 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T19:00:15.607Z,1431975615.607 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:00:15.980Z,1431975615.980 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:15.980Z,1431975615.980 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:16.742Z,1431975616.742 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:16.761Z,1431975616.761 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:17.758Z,1431975617.758 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:17.759Z,1431975617.759 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:18.625Z,1431975618.625 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T19:00:18.625Z,1431975618.625 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:00:18.891Z,1431975618.891 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:18.891Z,1431975618.891 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:19.611Z,1431975619.611 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:19.611Z,1431975619.611 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:19.871Z,1431975619.871 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x41951c]
2015-05-18T19:00:19.871Z,1431975619.871 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41956c]
2015-05-18T19:00:20.685Z,1431975620.685 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:20.685Z,1431975620.685 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:21.857Z,1431975621.857 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:21.857Z,1431975621.857 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:22.424Z,1431975622.424 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:22.440Z,1431975622.440 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:22.948Z,1431975622.948 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:22.962Z,1431975622.962 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:23.404Z,1431975623.404 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:23.404Z,1431975623.404 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:23.437Z,1431975623.437 [BPC1](DEBUG): Received data from all battery sticks
2015-05-18T19:00:24.240Z,1431975624.240 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:24.241Z,1431975624.241 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:25.683Z,1431975625.683 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195bc]
2015-05-18T19:00:25.684Z,1431975625.684 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41960c]
2015-05-18T19:00:26.177Z,1431975626.177 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:26.177Z,1431975626.177 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:26.822Z,1431975626.822 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:26.834Z,1431975626.834 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:27.168Z,1431975627.168 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:27.168Z,1431975627.168 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:27.810Z,1431975627.810 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:27.812Z,1431975627.812 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:29.303Z,1431975629.303 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:29.303Z,1431975629.303 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:29.808Z,1431975629.808 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:29.808Z,1431975629.808 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:30.241Z,1431975630.241 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195cc]
2015-05-18T19:00:30.242Z,1431975630.242 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41961c]
2015-05-18T19:00:30.759Z,1431975630.759 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:30.760Z,1431975630.760 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:32.317Z,1431975632.317 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:32.318Z,1431975632.318 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:33.181Z,1431975633.181 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:33.182Z,1431975633.182 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:33.368Z,1431975633.368 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:33.368Z,1431975633.368 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:34.082Z,1431975634.082 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:34.082Z,1431975634.082 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:34.115Z,1431975634.115 [BPC1](ERROR): Failed to parse bank A battery data
2015-05-18T19:00:34.115Z,1431975634.115 [BPC1] Data Fault, FailCount= 1
2015-05-18T19:00:34.115Z,1431975634.115 [BPC1](ERROR): Data Fault
2015-05-18T19:00:34.223Z,1431975634.223 [CBIT](ERROR): Data Fault in component: BPC1
2015-05-18T19:00:34.344Z,1431975634.344 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:34.345Z,1431975634.345 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:35.051Z,1431975635.051 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:35.065Z,1431975635.065 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:36.067Z,1431975636.067 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:00:36.068Z,1431975636.068 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:00:36.485Z,1431975636.485 [CBIT](INFO): Clearing failed state for component BPC1
2015-05-18T19:00:36.485Z,1431975636.485 [BPC1] No Fault, FailCount= 1
2015-05-18T19:01:03.086Z,1431975663.086 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:03.086Z,1431975663.086 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:03.776Z,1431975663.776 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:03.776Z,1431975663.776 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:04.097Z,1431975664.097 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:04.109Z,1431975664.109 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:04.939Z,1431975664.939 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:04.939Z,1431975664.939 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:06.149Z,1431975666.149 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:06.150Z,1431975666.150 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:06.674Z,1431975666.674 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:06.675Z,1431975666.675 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:07.597Z,1431975667.597 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:07.597Z,1431975667.597 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:08.280Z,1431975668.280 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:08.280Z,1431975668.280 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:09.751Z,1431975669.751 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:09.752Z,1431975669.752 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:10.351Z,1431975670.351 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:10.351Z,1431975670.351 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:11.224Z,1431975671.224 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195bc]
2015-05-18T19:01:11.232Z,1431975671.232 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41960c]
2015-05-18T19:01:11.970Z,1431975671.970 [Rowe_600](DEBUG): 3900 bytes available, but need 4084
2015-05-18T19:01:12.912Z,1431975672.912 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:12.913Z,1431975672.913 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:13.830Z,1431975673.830 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:13.831Z,1431975673.831 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:14.160Z,1431975674.160 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:14.160Z,1431975674.160 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:14.976Z,1431975674.976 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:15.334Z,1431975675.334 [Rowe_600](ERROR): Ensemble number mismatch: 795 in header, 797 in ensemble data matrix.
2015-05-18T19:01:15.348Z,1431975675.348 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4196a0]
2015-05-18T19:01:15.349Z,1431975675.349 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x4196f0]
2015-05-18T19:01:17.268Z,1431975677.268 [Rowe_600](DEBUG): 3951 bytes available, but need 4084
2015-05-18T19:01:17.844Z,1431975677.844 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:17.845Z,1431975677.845 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:18.482Z,1431975678.482 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:18.483Z,1431975678.483 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:18.767Z,1431975678.767 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:18.767Z,1431975678.767 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:19.406Z,1431975679.406 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:19.407Z,1431975679.407 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:20.439Z,1431975680.439 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:20.441Z,1431975680.441 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:21.633Z,1431975681.633 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:21.634Z,1431975681.634 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:22.480Z,1431975682.480 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195b4]
2015-05-18T19:01:22.480Z,1431975682.480 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419604]
2015-05-18T19:01:23.131Z,1431975683.131 [Rowe_600](ERROR): Ensemble number mismatch: 810 in header, 811 in ensemble data matrix.
2015-05-18T19:01:23.131Z,1431975683.131 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x41960c]
2015-05-18T19:01:23.132Z,1431975683.132 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41965c]
2015-05-18T19:01:24.752Z,1431975684.752 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:24.770Z,1431975684.770 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:25.260Z,1431975685.260 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:25.260Z,1431975685.260 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:25.913Z,1431975685.913 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:25.915Z,1431975685.915 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:26.235Z,1431975686.235 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:26.235Z,1431975686.235 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:26.879Z,1431975686.879 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:26.898Z,1431975686.898 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:28.899Z,1431975688.899 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:28.899Z,1431975688.899 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:29.350Z,1431975689.350 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x419596]
2015-05-18T19:01:29.352Z,1431975689.352 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x4195e6]
2015-05-18T19:01:29.843Z,1431975689.843 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:29.844Z,1431975689.844 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:30.547Z,1431975690.547 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:30.547Z,1431975690.547 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:31.700Z,1431975691.700 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:31.701Z,1431975691.701 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:33.159Z,1431975693.159 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:33.160Z,1431975693.160 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:33.755Z,1431975693.755 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:33.756Z,1431975693.756 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:35.247Z,1431975695.247 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:35.248Z,1431975695.248 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:01:36.252Z,1431975696.252 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:01:36.252Z,1431975696.252 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:03.056Z,1431975723.056 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x41952e]
2015-05-18T19:02:03.056Z,1431975723.056 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x41957e]
2015-05-18T19:02:03.661Z,1431975723.661 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:03.683Z,1431975723.683 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:05.282Z,1431975725.282 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:05.283Z,1431975725.283 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:05.878Z,1431975725.878 [Rowe_600](ERROR): Ensemble number mismatch: 837 in header, 838 in ensemble data matrix.
2015-05-18T19:02:05.878Z,1431975725.878 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x419684]
2015-05-18T19:02:05.878Z,1431975725.878 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x4196d4]
2015-05-18T19:02:06.575Z,1431975726.575 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:06.577Z,1431975726.577 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:06.801Z,1431975726.801 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:06.801Z,1431975726.801 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:07.451Z,1431975727.451 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:07.451Z,1431975727.451 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:08.713Z,1431975728.713 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T19:02:08.736Z,1431975728.736 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:02:09.459Z,1431975729.459 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:09.459Z,1431975729.459 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:10.368Z,1431975730.368 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:10.376Z,1431975730.376 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:10.671Z,1431975730.671 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T19:02:10.671Z,1431975730.671 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:02:11.464Z,1431975731.464 [Rowe_600](ERROR): first preamble found after 4426 bytes, which is more than sizeof( deviceResponse_ ) 4116
2015-05-18T19:02:12.597Z,1431975732.597 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T19:02:12.599Z,1431975732.599 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:02:13.303Z,1431975733.303 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:13.303Z,1431975733.303 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:14.003Z,1431975734.003 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:14.003Z,1431975734.003 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:14.895Z,1431975734.895 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:14.913Z,1431975734.913 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:16.431Z,1431975736.431 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:16.432Z,1431975736.432 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:16.973Z,1431975736.973 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:16.973Z,1431975736.973 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:17.993Z,1431975737.993 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:17.993Z,1431975737.993 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:18.590Z,1431975738.590 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:18.590Z,1431975738.590 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:18.758Z,1431975738.758 [BPC1](ERROR): Failed to receive battery data
2015-05-18T19:02:18.759Z,1431975738.759 [BPC1] Communications Fault, FailCount= 1
2015-05-18T19:02:18.759Z,1431975738.759 [BPC1](ERROR): Communications Fault
2015-05-18T19:02:19.328Z,1431975739.328 [CBIT](ERROR): Communications Fault in component: BPC1
2015-05-18T19:02:19.904Z,1431975739.904 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:19.905Z,1431975739.905 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:20.713Z,1431975740.713 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:20.713Z,1431975740.713 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:20.995Z,1431975740.995 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:20.995Z,1431975740.995 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:21.441Z,1431975741.441 [CBIT](INFO): Clearing failed state for component BPC1
2015-05-18T19:02:21.441Z,1431975741.441 [BPC1] No Fault, FailCount= 1
2015-05-18T19:02:21.564Z,1431975741.564 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:21.564Z,1431975741.564 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:22.178Z,1431975742.178 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:22.193Z,1431975742.193 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:23.975Z,1431975743.975 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:23.976Z,1431975743.976 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:24.517Z,1431975744.517 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:24.517Z,1431975744.517 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:25.166Z,1431975745.166 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:25.171Z,1431975745.171 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:25.411Z,1431975745.411 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:25.424Z,1431975745.424 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:26.100Z,1431975746.100 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:26.110Z,1431975746.110 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:27.103Z,1431975747.103 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:27.104Z,1431975747.104 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:28.136Z,1431975748.136 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:28.136Z,1431975748.136 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:28.561Z,1431975748.561 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:28.562Z,1431975748.562 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:29.044Z,1431975749.044 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:29.045Z,1431975749.045 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:29.704Z,1431975749.704 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:29.704Z,1431975749.704 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:31.301Z,1431975751.301 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:31.301Z,1431975751.301 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:31.893Z,1431975751.893 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:31.906Z,1431975751.906 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:32.315Z,1431975752.315 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:32.316Z,1431975752.316 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:32.752Z,1431975752.752 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:32.752Z,1431975752.752 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:33.416Z,1431975753.416 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:33.450Z,1431975753.450 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:35.069Z,1431975755.069 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:02:35.076Z,1431975755.076 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:02:35.638Z,1431975755.638 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T19:02:35.638Z,1431975755.638 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:02:35.737Z,1431975755.737 [Default:CheckIn:Read_GPS](INFO): Timed out from 2015-05-18T18:57:35.6Z
2015-05-18T19:02:35.738Z,1431975755.738 [Default:CheckIn:Read_GPS] Stopped
2015-05-18T19:02:35.738Z,1431975755.738 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-05-18T19:02:36.401Z,1431975756.401 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-05-18T19:03:08.882Z,1431975788.882 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:03:08.882Z,1431975788.882 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:03:09.221Z,1431975789.221 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T19:03:09.221Z,1431975789.221 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:03:09.821Z,1431975789.821 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:03:09.821Z,1431975789.821 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:03:36.137Z,1431975816.137 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x418b7b]
2015-05-18T19:03:36.148Z,1431975816.148 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x418bcb]
2015-05-18T19:03:36.182Z,1431975816.182 [BPC1](ERROR): Failed to receive battery data
2015-05-18T19:03:36.182Z,1431975816.182 [BPC1] Communications Fault, FailCount= 1
2015-05-18T19:03:36.183Z,1431975816.183 [BPC1](ERROR): Communications Fault
2015-05-18T19:03:36.428Z,1431975816.428 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-05-18T19:03:36.429Z,1431975816.429 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2015-05-18T19:03:36.429Z,1431975816.429 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-05-18T19:03:36.466Z,1431975816.466 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-05-18T19:03:36.519Z,1431975816.519 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2015-05-18T19:03:36.519Z,1431975816.519 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-05-18T19:03:36.565Z,1431975816.565 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-05-18T19:03:36.565Z,1431975816.565 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-05-18T19:03:36.565Z,1431975816.565 [CBIT](ERROR): Communications Fault in component: BPC1
2015-05-18T19:03:36.988Z,1431975816.988 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150515T204932/Courier0144.lzma
2015-05-18T19:03:37.726Z,1431975817.726 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:03:37.726Z,1431975817.726 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:03:38.053Z,1431975818.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-05-18T19:03:38.054Z,1431975818.054 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2015-05-18T19:03:38.066Z,1431975818.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-05-18T19:03:38.066Z,1431975818.066 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2015-05-18T19:03:38.266Z,1431975818.266 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:03:38.267Z,1431975818.267 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:03:38.350Z,1431975818.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-05-18T19:03:38.351Z,1431975818.351 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-05-18T19:03:38.563Z,1431975818.563 [DataOverHttps](INFO): Moved sent file to Logs/20150515T204932/Courier0144.lzma.bak
2015-05-18T19:03:38.563Z,1431975818.563 [DataOverHttps](INFO): SBD MOMSN=3475060
2015-05-18T19:03:38.795Z,1431975818.795 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195c4]
2015-05-18T19:03:38.796Z,1431975818.796 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419614]
2015-05-18T19:03:39.044Z,1431975819.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2.645719 seconds since last successful read, accepting data for 3.140000 seconds.
2015-05-18T19:03:39.094Z,1431975819.094 [CBIT](INFO): Clearing failed state for component BPC1
2015-05-18T19:03:39.094Z,1431975819.094 [BPC1] No Fault, FailCount= 1
2015-05-18T19:03:41.921Z,1431975821.921 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:03:41.922Z,1431975821.922 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:03:42.328Z,1431975822.328 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:03:42.328Z,1431975822.328 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:04:08.112Z,1431975848.112 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:04:08.112Z,1431975848.112 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:04:08.720Z,1431975848.720 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:04:08.721Z,1431975848.721 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:04:09.022Z,1431975849.022 [Rowe_600](DEBUG): 95 bytes available, but need 4084
2015-05-18T19:04:09.615Z,1431975849.615 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:04:09.617Z,1431975849.617 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:04:10.373Z,1431975850.373 [NAL9602](FAULT): GPS failed to acquire within timeout.
2015-05-18T19:04:10.373Z,1431975850.373 [NAL9602] Data Fault, FailCount= 1
2015-05-18T19:04:10.374Z,1431975850.374 [NAL9602](ERROR): Data Fault
2015-05-18T19:04:10.719Z,1431975850.719 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:04:10.727Z,1431975850.727 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:04:11.158Z,1431975851.158 [CBIT](ERROR): Data Fault in component: NAL9602
2015-05-18T19:04:11.318Z,1431975851.318 [NAL9602](INFO): Powering down
2015-05-18T19:04:11.705Z,1431975851.705 [Rowe_600](DEBUG): 71 bytes available, but need 4084
2015-05-18T19:04:38.193Z,1431975878.193 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:04:38.193Z,1431975878.193 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:04:38.252Z,1431975878.252 [BPC1](ERROR): Failed to receive battery data
2015-05-18T19:04:38.252Z,1431975878.252 [BPC1] Communications Fault, FailCount= 2
2015-05-18T19:04:38.252Z,1431975878.252 [BPC1](ERROR): Communications Fault
2015-05-18T19:04:38.383Z,1431975878.383 [CBIT](ERROR): Communications Fault in component: BPC1
2015-05-18T19:04:38.522Z,1431975878.522 [Rowe_600](DEBUG): reading 13-by-1 MATv4 matrix named E000009 from address [0x4195d4]
2015-05-18T19:04:38.538Z,1431975878.538 [Rowe_600](DEBUG): reading 54-by-1 MATv4 matrix named E000010 from address [0x419624]
2015-05-18T19:04:38.547Z,1431975878.547 [Rowe_600](ERROR): Only MATLAB type 10 (float32) matrices are supported, got MATLAB type 69
2015-05-18T19:04:38.996Z,1431975878.996 [CBIT](INFO): Clearing failed state for component NAL9602
2015-05-18T19:04:38.996Z,