2015-04-17T15:55:20.083Z,1429286120.083 [Supervisor](DEBUG): Initializing supervisor.
2015-04-17T15:55:20.086Z,1429286120.086 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-04-17T15:55:20.086Z,1429286120.086 [SyncHandler](INFO): Protected caller Thread ID is 4477
2015-04-17T15:55:20.087Z,1429286120.087 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-04-17T15:55:20.088Z,1429286120.088 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-04-17T15:55:20.088Z,1429286120.088 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4478
2015-04-17T15:55:20.091Z,1429286120.091 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-04-17T15:55:20.103Z,1429286120.103 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-04-17T15:55:20.104Z,1429286120.104 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-04-17T15:55:20.105Z,1429286120.105 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4479
2015-04-17T15:55:20.106Z,1429286120.106 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-04-17T15:55:20.107Z,1429286120.107 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-04-17T15:55:20.107Z,1429286120.107 [logger ThreadHandler](INFO): Protected caller Thread ID is 4480
2015-04-17T15:55:20.109Z,1429286120.109 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-04-17T15:55:20.110Z,1429286120.110 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-04-17T15:55:20.113Z,1429286120.113 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-04-17T15:55:20.613Z,1429286120.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-04-17T15:55:20.614Z,1429286120.614 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-04-17T15:55:20.784Z,1429286120.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-04-17T15:55:20.785Z,1429286120.785 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-04-17T15:55:20.897Z,1429286120.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-04-17T15:55:20.898Z,1429286120.898 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-04-17T15:55:21.023Z,1429286121.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-04-17T15:55:21.024Z,1429286121.024 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-04-17T15:55:21.113Z,1429286121.113 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-04-17T15:55:21.277Z,1429286121.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-04-17T15:55:21.278Z,1429286121.278 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-04-17T15:55:21.366Z,1429286121.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-04-17T15:55:21.366Z,1429286121.366 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-04-17T15:55:21.585Z,1429286121.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-04-17T15:55:21.588Z,1429286121.588 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-04-17T15:55:21.980Z,1429286121.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-04-17T15:55:21.981Z,1429286121.981 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-04-17T15:55:22.372Z,1429286122.372 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-04-17T15:55:22.373Z,1429286122.373 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-04-17T15:55:22.898Z,1429286122.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-04-17T15:55:22.899Z,1429286122.899 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-04-17T15:55:23.118Z,1429286123.118 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-04-17T15:55:23.119Z,1429286123.119 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-04-17T15:55:23.227Z,1429286123.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-04-17T15:55:23.227Z,1429286123.227 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-04-17T15:55:23.656Z,1429286123.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-04-17T15:55:23.657Z,1429286123.657 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-04-17T15:55:23.772Z,1429286123.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-04-17T15:55:23.774Z,1429286123.774 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2015-04-17T15:55:23.777Z,1429286123.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2015-04-17T15:55:23.886Z,1429286123.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2015-04-17T15:55:24.012Z,1429286124.012 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2015-04-17T15:55:24.114Z,1429286124.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2015-04-17T15:55:24.229Z,1429286124.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2015-04-17T15:55:24.327Z,1429286124.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2015-04-17T15:55:24.462Z,1429286124.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2015-04-17T15:55:24.627Z,1429286124.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2015-04-17T15:55:24.732Z,1429286124.732 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2015-04-17T15:55:24.827Z,1429286124.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2015-04-17T15:55:24.911Z,1429286124.911 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2015-04-17T15:55:25.011Z,1429286125.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2015-04-17T15:55:25.133Z,1429286125.133 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2015-04-17T15:55:25.386Z,1429286125.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-04-17T15:55:25.387Z,1429286125.387 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2015-04-17T15:55:25.400Z,1429286125.400 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-04-17T15:55:25.473Z,1429286125.473 [VerticalControl](DEBUG): Construct VerticalControl.
2015-04-17T15:55:25.609Z,1429286125.609 [VerticalControl] Loaded
2015-04-17T15:55:25.609Z,1429286125.609 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-04-17T15:55:25.610Z,1429286125.610 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-04-17T15:55:25.693Z,1429286125.693 [HorizontalControl] Loaded
2015-04-17T15:55:25.693Z,1429286125.693 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-04-17T15:55:25.694Z,1429286125.694 [SpeedControl](DEBUG): Construct SpeedControl.
2015-04-17T15:55:25.701Z,1429286125.701 [SpeedControl] Loaded
2015-04-17T15:55:25.701Z,1429286125.701 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-04-17T15:55:25.702Z,1429286125.702 [LoopControl](DEBUG): Construct LoopControl.
2015-04-17T15:55:25.703Z,1429286125.703 [LoopControl] Loaded
2015-04-17T15:55:25.703Z,1429286125.703 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-04-17T15:55:25.703Z,1429286125.703 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-04-17T15:55:25.704Z,1429286125.704 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-04-17T15:55:25.878Z,1429286125.878 [SBIT](DEBUG): Construct Startup Built In Test.
2015-04-17T15:55:25.890Z,1429286125.890 [SBIT] Loaded
2015-04-17T15:55:25.890Z,1429286125.890 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-04-17T15:55:25.891Z,1429286125.891 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-04-17T15:55:25.923Z,1429286125.923 [IBIT] Loaded
2015-04-17T15:55:25.924Z,1429286125.924 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-04-17T15:55:25.926Z,1429286125.926 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-04-17T15:55:26.083Z,1429286126.083 [CBIT] Loaded
2015-04-17T15:55:26.084Z,1429286126.084 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-04-17T15:55:26.084Z,1429286126.084 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-04-17T15:55:26.085Z,1429286126.085 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-04-17T15:55:26.113Z,1429286126.113 [DepthRateCalculator] Loaded
2015-04-17T15:55:26.113Z,1429286126.113 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-04-17T15:55:26.120Z,1429286126.120 [PitchRateCalculator] Loaded
2015-04-17T15:55:26.120Z,1429286126.120 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-04-17T15:55:26.133Z,1429286126.133 [SpeedCalculator] Loaded
2015-04-17T15:55:26.133Z,1429286126.133 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-04-17T15:55:26.152Z,1429286126.152 [TempGradientCalculator] Loaded
2015-04-17T15:55:26.152Z,1429286126.152 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-04-17T15:55:26.158Z,1429286126.158 [YawRateCalculator] Loaded
2015-04-17T15:55:26.159Z,1429286126.159 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-04-17T15:55:26.159Z,1429286126.159 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-04-17T15:55:26.160Z,1429286126.160 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-04-17T15:55:26.248Z,1429286126.248 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-04-17T15:55:26.249Z,1429286126.249 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-04-17T15:55:26.559Z,1429286126.559 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-04-17T15:55:26.559Z,1429286126.559 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-04-17T15:55:26.668Z,1429286126.668 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-04-17T15:55:26.669Z,1429286126.669 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-04-17T15:55:26.720Z,1429286126.720 [DeadReckonUsingSpeedCalculator] Loaded
2015-04-17T15:55:26.720Z,1429286126.720 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-04-17T15:55:26.739Z,1429286126.739 [NavChart] Loaded
2015-04-17T15:55:26.740Z,1429286126.740 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-04-17T15:55:26.745Z,1429286126.745 [UniversalFixResidualReporter] Loaded
2015-04-17T15:55:26.745Z,1429286126.745 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-04-17T15:55:26.746Z,1429286126.746 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-04-17T15:55:26.746Z,1429286126.746 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-04-17T15:55:26.759Z,1429286126.759 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-04-17T15:55:26.759Z,1429286126.759 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-04-17T15:55:26.965Z,1429286126.965 [Aanderaa_O2] Loaded
2015-04-17T15:55:26.965Z,1429286126.965 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2015-04-17T15:55:27.053Z,1429286127.053 [CTD_NeilBrown] Loaded
2015-04-17T15:55:27.053Z,1429286127.053 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-04-17T15:55:27.054Z,1429286127.054 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405764E0
2015-04-17T15:55:27.055Z,1429286127.055 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4565
2015-04-17T15:55:27.069Z,1429286127.069 [ISUS] Loaded
2015-04-17T15:55:27.070Z,1429286127.070 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2015-04-17T15:55:27.086Z,1429286127.086 [PAR_Licor] Loaded
2015-04-17T15:55:27.086Z,1429286127.086 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-04-17T15:55:27.141Z,1429286127.141 [WetLabsBB2FL] Loaded
2015-04-17T15:55:27.141Z,1429286127.141 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-04-17T15:55:27.143Z,1429286127.143 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405A64E0
2015-04-17T15:55:27.143Z,1429286127.143 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4566
2015-04-17T15:55:27.144Z,1429286127.144 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-04-17T15:55:27.144Z,1429286127.144 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-04-17T15:55:27.710Z,1429286127.710 [AHRS_sp3003D] Loaded
2015-04-17T15:55:27.711Z,1429286127.711 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2015-04-17T15:55:27.726Z,1429286127.726 [Depth_Keller] Loaded
2015-04-17T15:55:27.727Z,1429286127.727 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-04-17T15:55:27.732Z,1429286127.732 [DropWeight] Loaded
2015-04-17T15:55:27.733Z,1429286127.733 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-04-17T15:55:27.889Z,1429286127.889 [DVL_micro] Loaded
2015-04-17T15:55:27.889Z,1429286127.889 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2015-04-17T15:55:27.998Z,1429286127.998 [NAL9602] Loaded
2015-04-17T15:55:27.998Z,1429286127.998 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-04-17T15:55:28.047Z,1429286128.047 [Onboard] Loaded
2015-04-17T15:55:28.047Z,1429286128.047 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-04-17T15:55:28.055Z,1429286128.055 [Radio_Surface] Loaded
2015-04-17T15:55:28.055Z,1429286128.055 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-04-17T15:55:28.056Z,1429286128.056 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407F64E0
2015-04-17T15:55:28.057Z,1429286128.057 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4567
2015-04-17T15:55:30.189Z,1429286130.189 [BPC1] Loaded
2015-04-17T15:55:30.189Z,1429286130.189 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-04-17T15:55:30.189Z,1429286130.189 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-04-17T15:55:30.190Z,1429286130.190 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-04-17T15:55:30.326Z,1429286130.326 [BuoyancyServo] Loaded
2015-04-17T15:55:30.326Z,1429286130.326 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-04-17T15:55:30.340Z,1429286130.340 [ElevatorServo] Loaded
2015-04-17T15:55:30.341Z,1429286130.341 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-04-17T15:55:30.353Z,1429286130.353 [MassServo] Loaded
2015-04-17T15:55:30.354Z,1429286130.354 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-04-17T15:55:30.367Z,1429286130.367 [RudderServo] Loaded
2015-04-17T15:55:30.367Z,1429286130.367 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-04-17T15:55:30.380Z,1429286130.380 [ThrusterServo] Loaded
2015-04-17T15:55:30.381Z,1429286130.381 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-04-17T15:55:30.381Z,1429286130.381 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-04-17T15:55:30.382Z,1429286130.382 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-04-17T15:55:30.531Z,1429286130.531 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-04-17T15:55:30.531Z,1429286130.531 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-04-17T15:55:30.572Z,1429286130.572 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-04-17T15:55:30.574Z,1429286130.574 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-04-17T15:55:30.575Z,1429286130.575 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-04-17T15:55:30.581Z,1429286130.581 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-04-17T15:55:30.583Z,1429286130.583 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408A74E0
2015-04-17T15:55:30.583Z,1429286130.583 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4568
2015-04-17T15:55:30.588Z,1429286130.588 [Supervisor](INFO): Main Thread ID is 765
2015-04-17T15:55:30.588Z,1429286130.588 [Supervisor](DEBUG): Running supervisor.
2015-04-17T15:55:30.588Z,1429286130.588 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4569
2015-04-17T15:55:30.591Z,1429286130.591 [controlThread ThreadHandler](INFO): Handler Thread ID is 4570
2015-04-17T15:55:30.592Z,1429286130.592 [controlThread](DEBUG): Initializing ControlThread
2015-04-17T15:55:30.593Z,1429286130.593 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-04-17T15:55:30.594Z,1429286130.594 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-04-17T15:55:30.595Z,1429286130.595 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-04-17T15:55:30.596Z,1429286130.596 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-04-17T15:55:30.596Z,1429286130.596 [SBIT](INFO): Initialize SBIT Component.
2015-04-17T15:55:30.596Z,1429286130.596 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12068
2015-04-17T15:55:30.597Z,1429286130.597 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-04-17T15:55:30.597Z,1429286130.597 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-04-17T15:55:30.598Z,1429286130.598 [IBIT](INFO): Initialize IBIT Component.
2015-04-17T15:55:30.598Z,1429286130.598 [CBIT](DEBUG): Initialize CBIT Component.
2015-04-17T15:55:30.599Z,1429286130.599 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-04-17T15:55:30.600Z,1429286130.600 [logger ThreadHandler](INFO): Handler Thread ID is 4571
2015-04-17T15:55:30.625Z,1429286130.625 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-04-17T15:55:30.626Z,1429286130.626 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-04-17T15:55:30.626Z,1429286130.626 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-04-17T15:55:30.626Z,1429286130.626 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-04-17T15:55:30.627Z,1429286130.627 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-04-17T15:55:30.627Z,1429286130.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-04-17T15:55:30.628Z,1429286130.628 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-04-17T15:55:30.628Z,1429286130.628 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-04-17T15:55:30.628Z,1429286130.628 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-04-17T15:55:30.642Z,1429286130.642 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4572
2015-04-17T15:55:30.642Z,1429286130.642 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2015-04-17T15:55:30.644Z,1429286130.644 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-04-17T15:55:30.649Z,1429286130.649 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4574
2015-04-17T15:55:30.651Z,1429286130.651 [WetLabsBB2FL](INFO): Powering down
2015-04-17T15:55:30.670Z,1429286130.670 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2015-04-17T15:55:30.698Z,1429286130.698 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4575
2015-04-17T15:55:30.704Z,1429286130.704 [Radio_Surface](INFO): Powering up
2015-04-17T15:55:30.716Z,1429286130.716 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-04-17T15:55:30.718Z,1429286130.718 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4576
2015-04-17T15:55:30.729Z,1429286130.729 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-04-17T15:55:30.731Z,1429286130.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-04-17T15:55:30.732Z,1429286130.732 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-04-17T15:55:30.732Z,1429286130.732 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-04-17T15:55:30.732Z,1429286130.732 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-04-17T15:55:30.732Z,1429286130.732 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-04-17T15:55:30.733Z,1429286130.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-04-17T15:55:30.733Z,1429286130.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-04-17T15:55:30.733Z,1429286130.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-04-17T15:55:30.785Z,1429286130.785 [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-04-17T15:55:30.786Z,1429286130.786 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-04-17T15:55:30.878Z,1429286130.878 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2015-04-17T15:55:30.896Z,1429286130.896 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2015-04-17T15:55:30.913Z,1429286130.913 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2015-04-17T15:55:30.946Z,1429286130.946 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-04-17T15:55:30.952Z,1429286130.952 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-04-17T15:55:30.977Z,1429286130.977 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-04-17T15:55:31.022Z,1429286131.022 [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="TimeMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <DefineArg Name="ElapsedSinceMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <Aggregate Id="StartClock">

        <Sequence/>

        <Assign><Arg Name="TimeMissionWasStarted"/><Universal:time/></Assign>

    </Aggregate>

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

<!-- TODO: Do we really want this When operating on platform_communications? or on something else like time_fix? -->

<!-- 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:latitude_fix/>
        </ReadDatum>

<!-- Do data comms -->

        <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 drop weight due to communications timeout</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

        <When>
            <Elapsed><Arg Name="ElapsedSinceMissionWasStarted"/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
        </When>

        <Assign><Arg Name="ElapsedSinceMissionWasStarted"/><Universal:time/><Sub><Arg Name="TimeMissionWasStarted"/></Sub></Assign>

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

    </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-04-17T15:55:31.028Z,1429286131.028 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ISUS,PAR_Licor,AHRS_sp3003D,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-04-17T15:55:31.090Z,1429286131.090 [CTD_NeilBrown](DEBUG): CTD uart timeout: serial timeout
2015-04-17T15:55:31.090Z,1429286131.090 [CTD_NeilBrown](ERROR): Could not get real or simulated CTD data. startTime.elapsed() = 0.420252
2015-04-17T15:55:31.214Z,1429286131.214 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2015-04-17T15:55:31.242Z,1429286131.242 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2015-04-17T15:55:31.309Z,1429286131.309 [DVL_micro](INFO): Initializing
2015-04-17T15:55:31.511Z,1429286131.511 [DepthRateCalculator](ERROR): Depth measurement is not active
2015-04-17T15:55:31.567Z,1429286131.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-04-17T15:55:31.699Z,1429286131.699 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-04-17T15:55:31.707Z,1429286131.707 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-04-17T15:55:31.730Z,1429286131.730 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-04-17T15:55:31.735Z,1429286131.735 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-04-17T15:55:31.757Z,1429286131.757 [MassServo](DEBUG): Initializing EZServoServo.
2015-04-17T15:55:31.763Z,1429286131.763 [MassServo](DEBUG): Initializing MassServo.
2015-04-17T15:55:31.769Z,1429286131.769 [RudderServo](DEBUG): Initializing EZServoServo.
2015-04-17T15:55:31.775Z,1429286131.775 [RudderServo](DEBUG): Initializing RudderServo.
2015-04-17T15:55:31.781Z,1429286131.781 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-04-17T15:55:31.787Z,1429286131.787 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-04-17T15:55:32.171Z,1429286132.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-04-17T15:55:32.400Z,1429286132.400 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-04-17T15:55:33.079Z,1429286133.079 [NAL9602](INFO): Powering up NAL9602
2015-04-17T15:55:33.124Z,1429286133.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-04-17T15:55:33.567Z,1429286133.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-04-17T15:55:34.070Z,1429286134.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-04-17T15:55:41.436Z,1429286141.436 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T15:55:41.436Z,1429286141.436 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T15:55:41.436Z,1429286141.436 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T15:55:41.886Z,1429286141.886 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T15:55:42.019Z,1429286142.019 [Aanderaa_O2](INFO): Powering down
2015-04-17T15:55:42.385Z,1429286142.385 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T15:55:42.385Z,1429286142.385 [DVL_micro] Communications Fault, FailCount= 1
2015-04-17T15:55:42.385Z,1429286142.385 [DVL_micro](ERROR): Communications Fault
2015-04-17T15:55:42.506Z,1429286142.506 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T15:55:42.908Z,1429286142.908 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T15:55:43.591Z,1429286143.591 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T15:55:43.693Z,1429286143.693 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T15:55:43.693Z,1429286143.693 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T15:55:44.205Z,1429286144.205 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T15:55:44.206Z,1429286144.206 [DVL_micro] No Fault, FailCount= 1
2015-04-17T15:55:44.567Z,1429286144.567 [DVL_micro](INFO): Initializing
2015-04-17T15:55:44.600Z,1429286144.600 [NAL9602](INFO): NAL9602 initialized
2015-04-17T15:55:46.215Z,1429286146.215 [SBIT](IMPORTANT): Beginning Startup BIT
2015-04-17T15:55:46.218Z,1429286146.218 [CBIT](IMPORTANT): Beginning GF scan
2015-04-17T15:55:53.804Z,1429286153.804 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T15:55:53.804Z,1429286153.804 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T15:55:53.805Z,1429286153.805 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T15:55:54.195Z,1429286154.195 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T15:55:54.322Z,1429286154.322 [Aanderaa_O2](INFO): Powering down
2015-04-17T15:55:55.101Z,1429286155.101 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T15:55:55.101Z,1429286155.101 [DVL_micro] Communications Fault, FailCount= 2
2015-04-17T15:55:55.101Z,1429286155.101 [DVL_micro](ERROR): Communications Fault
2015-04-17T15:55:55.186Z,1429286155.186 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T15:55:55.608Z,1429286155.608 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T15:55:55.687Z,1429286155.687 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T15:55:55.687Z,1429286155.687 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T15:55:56.063Z,1429286156.063 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T15:55:56.576Z,1429286156.576 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T15:55:56.576Z,1429286156.576 [DVL_micro] No Fault, FailCount= 2
2015-04-17T15:55:56.950Z,1429286156.950 [DVL_micro](INFO): Initializing
2015-04-17T15:56:02.355Z,1429286162.355 [NAL9602](INFO): SBD MO Status=0, MOMSN=52447, MT Status=0, MTMSN=0
2015-04-17T15:56:02.355Z,1429286162.355 [NAL9602](INFO): No messages in MT queue
2015-04-17T15:56:05.944Z,1429286165.944 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T15:56:05.945Z,1429286165.945 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T15:56:05.945Z,1429286165.945 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T15:56:06.428Z,1429286166.428 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T15:56:06.429Z,1429286166.429 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T15:56:06.549Z,1429286166.549 [Aanderaa_O2](INFO): Powering down
2015-04-17T15:56:07.416Z,1429286167.416 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T15:56:07.416Z,1429286167.416 [DVL_micro] Communications Fault, FailCount= 3
2015-04-17T15:56:07.416Z,1429286167.416 [DVL_micro](ERROR): Communications Fault
2015-04-17T15:56:07.742Z,1429286167.742 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T15:56:08.151Z,1429286168.151 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T15:56:08.719Z,1429286168.719 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T15:56:09.269Z,1429286169.269 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T15:56:09.270Z,1429286169.270 [DVL_micro] No Fault, FailCount= 3
2015-04-17T15:56:09.605Z,1429286169.605 [DVL_micro](INFO): Initializing
2015-04-17T15:56:10.077Z,1429286170.077 [NAL9602](INFO): GPS fix at 20150417T155357: (36.802586, -121.787073)
2015-04-17T15:56:12.559Z,1429286172.559 [CBIT](IMPORTANT): No ground fault detected
2015-04-17T15:56:17.768Z,1429286177.768 [NAL9602](INFO): Powering down
2015-04-17T15:56:20.444Z,1429286180.444 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T15:56:20.444Z,1429286180.444 [DVL_micro] Communications Fault, FailCount= 4
2015-04-17T15:56:20.444Z,1429286180.444 [DVL_micro](ERROR): Communications Fault
2015-04-17T15:56:20.621Z,1429286180.621 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T15:56:21.067Z,1429286181.067 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T15:56:21.509Z,1429286181.509 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T15:56:22.025Z,1429286182.025 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T15:56:22.025Z,1429286182.025 [DVL_micro] No Fault, FailCount= 4
2015-04-17T15:56:22.429Z,1429286182.429 [DVL_micro](INFO): Initializing
2015-04-17T15:56:32.987Z,1429286192.987 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T15:56:32.987Z,1429286192.987 [DVL_micro] Communications Fault, FailCount= 5
2015-04-17T15:56:32.987Z,1429286192.987 [DVL_micro](ERROR): Communications Fault
2015-04-17T15:56:33.075Z,1429286193.075 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T15:56:33.075Z,1429286193.075 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2015-04-17T15:56:33.515Z,1429286193.515 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T15:56:33.582Z,1429286193.582 [CommandLine](FAULT): Scheduling is paused
2015-04-17T15:56:33.953Z,1429286193.953 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T15:56:39.918Z,1429286199.918 [SBIT](IMPORTANT): SBIT PASSED
2015-04-17T15:56:40.295Z,1429286200.295 [MissionManager](IMPORTANT): Started mission Startup
2015-04-17T15:56:40.295Z,1429286200.295 [Startup] Running Loop=1
2015-04-17T15:56:40.295Z,1429286200.295 [Startup](INFO): Aggregate::initialize Startup
2015-04-17T15:56:40.295Z,1429286200.295 [Startup:A.GoToSurface] Running Loop=1
2015-04-17T15:56:40.296Z,1429286200.296 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-04-17T15:56:40.296Z,1429286200.296 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-04-17T15:56:40.297Z,1429286200.297 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-04-17T15:56:40.298Z,1429286200.298 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-04-17T15:56:40.304Z,1429286200.304 [Startup:StartupSatComms] Running Loop=1
2015-04-17T15:56:40.305Z,1429286200.305 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2015-04-17T15:56:40.305Z,1429286200.305 [Startup:StartupSatComms:A] Running Loop=1
2015-04-17T15:56:40.732Z,1429286200.732 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-04-17T15:56:41.136Z,1429286201.136 [NAL9602](INFO): Powering up
2015-04-17T15:56:52.189Z,1429286212.189 [NAL9602](INFO): NAL9602 initialized
2015-04-17T15:57:14.029Z,1429286234.029 [NAL9602](INFO): SBD MO Status=0, MOMSN=52448, MT Status=0, MTMSN=0
2015-04-17T15:57:14.029Z,1429286234.029 [NAL9602](INFO): No messages in MT queue
2015-04-17T15:57:15.369Z,1429286235.369 [NAL9602](INFO): GPS fix at 20150417T155502: (36.802591, -121.787060)
2015-04-17T15:57:15.385Z,1429286235.385 [Startup:StartupSatComms:A] Stopped
2015-04-17T15:57:15.385Z,1429286235.385 [Startup:StartupSatComms:B] Running Loop=1
2015-04-17T15:57:15.826Z,1429286235.826 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-04-17T15:57:30.082Z,1429286250.082 [BPC1](ERROR): Failed to receive battery data
2015-04-17T15:57:30.082Z,1429286250.082 [BPC1] Communications Fault, FailCount= 1
2015-04-17T15:57:30.082Z,1429286250.082 [BPC1](ERROR): Communications Fault
2015-04-17T15:57:30.144Z,1429286250.144 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T15:57:30.952Z,1429286250.952 [NAL9602](INFO): SBD MO Status=1, MOMSN=52449, MT Status=0, MTMSN=0
2015-04-17T15:57:30.000Z,1429286251.000 [NAL9602](INFO): Sent 79 bytes from file Logs/20150417T022810/Courier0148.lzma
2015-04-17T15:57:31.000Z,1429286251.000 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:57:31.002Z,1429286251.002 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Courier0148.lzma.parts/0000.sbd
2015-04-17T15:57:31.003Z,1429286251.003 [NAL9602](INFO): Completed sending Logs/20150417T022810/Courier0148.lzma
2015-04-17T15:57:31.673Z,1429286251.673 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T15:57:31.674Z,1429286251.674 [BPC1] No Fault, FailCount= 1
2015-04-17T15:57:40.886Z,1429286260.886 [NAL9602](INFO): SBD MO Status=1, MOMSN=52450, MT Status=0, MTMSN=0
2015-04-17T15:57:40.944Z,1429286260.944 [NAL9602](INFO): Sent 277 bytes from file Logs/20150417T022810/Courier0152.lzma
2015-04-17T15:57:40.944Z,1429286260.944 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:57:40.946Z,1429286260.946 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Courier0152.lzma.parts/0000.sbd
2015-04-17T15:57:40.946Z,1429286260.946 [NAL9602](INFO): Completed sending Logs/20150417T022810/Courier0152.lzma
2015-04-17T15:57:49.203Z,1429286269.203 [NAL9602](INFO): SBD MO Status=1, MOMSN=52451, MT Status=0, MTMSN=0
2015-04-17T15:57:49.276Z,1429286269.276 [NAL9602](INFO): Sent 227 bytes from file Logs/20150417T022810/Courier0156.lzma
2015-04-17T15:57:49.277Z,1429286269.277 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:57:49.278Z,1429286269.278 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Courier0156.lzma.parts/0000.sbd
2015-04-17T15:57:49.279Z,1429286269.279 [NAL9602](INFO): Completed sending Logs/20150417T022810/Courier0156.lzma
2015-04-17T15:58:11.418Z,1429286291.418 [NAL9602](INFO): SBD MO Status=1, MOMSN=52452, MT Status=0, MTMSN=0
2015-04-17T15:58:11.479Z,1429286291.479 [NAL9602](INFO): Sent 133 bytes from file Logs/20150417T022810/Courier0160.lzma
2015-04-17T15:58:11.479Z,1429286291.479 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:58:11.481Z,1429286291.481 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Courier0160.lzma.parts/0000.sbd
2015-04-17T15:58:11.481Z,1429286291.481 [NAL9602](INFO): Completed sending Logs/20150417T022810/Courier0160.lzma
2015-04-17T15:58:15.438Z,1429286295.438 [Startup:StartupSatComms:B](INFO): Timed out from 2015-04-17T15:57:15.4Z
2015-04-17T15:58:15.438Z,1429286295.438 [Startup:StartupSatComms:B] Stopped
2015-04-17T15:58:15.438Z,1429286295.438 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-04-17T15:58:15.439Z,1429286295.439 [Startup:StartupSatComms] Stopped
2015-04-17T15:58:15.439Z,1429286295.439 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2015-04-17T15:58:15.440Z,1429286295.440 [Startup](INFO): Completed Startup
2015-04-17T15:58:15.440Z,1429286295.440 [Startup] Stopped
2015-04-17T15:58:15.440Z,1429286295.440 [Startup](INFO): Aggregate::uninitialize Startup
2015-04-17T15:58:15.440Z,1429286295.440 [Startup:A.GoToSurface] Stopped
2015-04-17T15:58:15.440Z,1429286295.440 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-04-17T15:58:15.894Z,1429286295.894 [MissionManager](IMPORTANT): Started mission Default
2015-04-17T15:58:15.894Z,1429286295.894 [Default] Running Loop=1
2015-04-17T15:58:15.894Z,1429286295.894 [Default](INFO): Aggregate::initialize Default
2015-04-17T15:58:15.895Z,1429286295.895 [Default:B.GoToSurface] Running Loop=1
2015-04-17T15:58:15.895Z,1429286295.895 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-04-17T15:58:15.895Z,1429286295.895 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-04-17T15:58:15.895Z,1429286295.895 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-04-17T15:58:15.896Z,1429286295.896 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-04-17T15:58:15.896Z,1429286295.896 [Default:StartClock] Running Loop=1
2015-04-17T15:58:15.896Z,1429286295.896 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2015-04-17T15:58:15.896Z,1429286295.896 [Default:StartClock:A] Running Loop=1
2015-04-17T15:58:15.897Z,1429286295.897 [Default:CheckIn] Running Loop=1
2015-04-17T15:58:15.897Z,1429286295.897 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-04-17T15:58:15.897Z,1429286295.897 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-04-17T15:58:15.897Z,1429286295.897 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-04-17T15:58:15.897Z,1429286295.897 [Default:CheckIn:Read_GPS] Running Loop=1
2015-04-17T15:58:15.898Z,1429286295.898 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-04-17T15:58:15.900Z,1429286295.900 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-04-17T15:58:15.914Z,1429286295.914 [Default:StartClock:A] Stopped
2015-04-17T15:58:15.914Z,1429286295.914 [Default:StartClock](INFO): Completed Default:StartClock
2015-04-17T15:58:15.914Z,1429286295.914 [Default:StartClock] Stopped
2015-04-17T15:58:15.914Z,1429286295.914 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2015-04-17T15:58:16.321Z,1429286296.321 [Default:WaitAtTheSurface] Running Loop=1
2015-04-17T15:58:16.322Z,1429286296.322 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2015-04-17T15:58:16.322Z,1429286296.322 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-04-17T15:58:16.322Z,1429286296.322 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2015-04-17T15:58:16.765Z,1429286296.765 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-04-17T15:58:19.791Z,1429286299.791 [NAL9602](INFO): SBD MO Status=1, MOMSN=52453, MT Status=0, MTMSN=0
2015-04-17T15:58:19.840Z,1429286299.840 [NAL9602](INFO): Sent 186 bytes from file Logs/20150417T022810/Courier0164.lzma
2015-04-17T15:58:19.840Z,1429286299.840 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:58:19.842Z,1429286299.842 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Courier0164.lzma.parts/0000.sbd
2015-04-17T15:58:19.842Z,1429286299.842 [NAL9602](INFO): Completed sending Logs/20150417T022810/Courier0164.lzma
2015-04-17T15:58:21.135Z,1429286301.135 [NAL9602](INFO): GPS fix at 20150417T155608: (36.802621, -121.787060)
2015-04-17T15:58:21.171Z,1429286301.171 [Default:CheckIn:Read_GPS] Stopped
2015-04-17T15:58:21.171Z,1429286301.171 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-04-17T15:58:21.613Z,1429286301.613 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-04-17T15:58:36.067Z,1429286316.067 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T15:58:36.067Z,1429286316.067 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T15:58:40.278Z,1429286320.278 [NAL9602](INFO): SBD MO Status=1, MOMSN=52454, MT Status=0, MTMSN=0
2015-04-17T15:58:40.331Z,1429286320.331 [NAL9602](INFO): Sent 124 bytes from file Logs/20150417T022810/Courier0168.lzma
2015-04-17T15:58:40.332Z,1429286320.332 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:58:40.334Z,1429286320.334 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Courier0168.lzma.parts/0000.sbd
2015-04-17T15:58:40.334Z,1429286320.334 [NAL9602](INFO): Completed sending Logs/20150417T022810/Courier0168.lzma
2015-04-17T15:58:46.085Z,1429286326.085 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T15:58:46.085Z,1429286326.085 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T15:58:46.085Z,1429286326.085 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T15:58:46.520Z,1429286326.520 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T15:58:46.653Z,1429286326.653 [Aanderaa_O2](INFO): Powering down
2015-04-17T15:58:47.883Z,1429286327.883 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T15:58:47.883Z,1429286327.883 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T15:58:56.276Z,1429286336.276 [NAL9602](INFO): SBD MO Status=2, MOMSN=52455, MT Status=2, MTMSN=0
2015-04-17T15:58:56.276Z,1429286336.276 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T15:58:58.115Z,1429286338.115 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T15:58:58.116Z,1429286338.116 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T15:58:58.116Z,1429286338.116 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T15:58:58.515Z,1429286338.515 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T15:58:58.640Z,1429286338.640 [Aanderaa_O2](INFO): Powering down
2015-04-17T15:58:59.942Z,1429286339.942 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T15:58:59.942Z,1429286339.942 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T15:59:10.134Z,1429286350.134 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T15:59:10.134Z,1429286350.134 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T15:59:10.134Z,1429286350.134 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T15:59:10.589Z,1429286350.589 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T15:59:10.598Z,1429286350.598 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T15:59:10.731Z,1429286350.731 [Aanderaa_O2](INFO): Powering down
2015-04-17T15:59:22.600Z,1429286362.600 [NAL9602](INFO): SBD MO Status=1, MOMSN=52455, MT Status=0, MTMSN=0
2015-04-17T15:59:22.653Z,1429286362.653 [NAL9602](INFO): Sent 99 bytes from file Logs/20150417T022810/Courier0172.lzma
2015-04-17T15:59:22.653Z,1429286362.653 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:59:22.655Z,1429286362.655 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Courier0172.lzma.parts/0000.sbd
2015-04-17T15:59:22.656Z,1429286362.656 [NAL9602](INFO): Completed sending Logs/20150417T022810/Courier0172.lzma
2015-04-17T15:59:33.052Z,1429286373.052 [NAL9602](INFO): SBD MO Status=1, MOMSN=52456, MT Status=0, MTMSN=0
2015-04-17T15:59:33.101Z,1429286373.101 [NAL9602](INFO): Sent 25 bytes from file Logs/20150417T022810/Courier0176.lzma
2015-04-17T15:59:33.102Z,1429286373.102 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:59:33.104Z,1429286373.104 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Courier0176.lzma.parts/0000.sbd
2015-04-17T15:59:33.104Z,1429286373.104 [NAL9602](INFO): Completed sending Logs/20150417T022810/Courier0176.lzma
2015-04-17T15:59:50.836Z,1429286390.836 [NAL9602](INFO): SBD MO Status=1, MOMSN=52457, MT Status=0, MTMSN=0
2015-04-17T15:59:50.889Z,1429286390.889 [NAL9602](INFO): Sent 271 bytes from file Logs/20150417T155520/Courier0000.lzma
2015-04-17T15:59:50.889Z,1429286390.889 [NAL9602](INFO): Packets left to send: 0
2015-04-17T15:59:50.891Z,1429286390.891 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0000.lzma.parts/0000.sbd
2015-04-17T15:59:50.891Z,1429286390.891 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0000.lzma
2015-04-17T16:00:01.368Z,1429286401.368 [NAL9602](INFO): SBD MO Status=1, MOMSN=52458, MT Status=0, MTMSN=0
2015-04-17T16:00:01.427Z,1429286401.427 [NAL9602](INFO): Sent 61 bytes from file Logs/20150417T155520/Courier0004.lzma
2015-04-17T16:00:01.427Z,1429286401.427 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:00:01.428Z,1429286401.428 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0004.lzma.parts/0000.sbd
2015-04-17T16:00:01.429Z,1429286401.429 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0004.lzma
2015-04-17T16:00:10.738Z,1429286410.738 [NAL9602](INFO): SBD MO Status=1, MOMSN=52459, MT Status=0, MTMSN=0
2015-04-17T16:00:10.792Z,1429286410.792 [NAL9602](INFO): Sent 252 bytes from file Logs/20150417T022810/Express0149.lzma
2015-04-17T16:00:10.792Z,1429286410.792 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:00:10.794Z,1429286410.794 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0149.lzma.parts/0000.sbd
2015-04-17T16:00:10.794Z,1429286410.794 [NAL9602](INFO): Completed sending Logs/20150417T022810/Express0149.lzma
2015-04-17T16:00:27.352Z,1429286427.352 [NAL9602](INFO): SBD MO Status=1, MOMSN=52460, MT Status=0, MTMSN=0
2015-04-17T16:00:27.409Z,1429286427.409 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0153.lzma
2015-04-17T16:00:27.410Z,1429286427.410 [NAL9602](INFO): Packets left to send: 3
2015-04-17T16:00:27.412Z,1429286427.412 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0153.lzma.parts/0003.sbd
2015-04-17T16:00:31.041Z,1429286431.041 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:00:31.041Z,1429286431.041 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:00:31.041Z,1429286431.041 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:00:31.044Z,1429286431.044 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:00:31.044Z,1429286431.044 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:00:31.458Z,1429286431.458 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 2.253088 min 
2015-04-17T16:00:31.460Z,1429286431.460 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:00:31.460Z,1429286431.460 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:00:31.460Z,1429286431.460 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:00:31.461Z,1429286431.461 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:00:56.778Z,1429286456.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=52461, MT Status=2, MTMSN=0
2015-04-17T16:00:56.778Z,1429286456.778 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:01:40.150Z,1429286500.150 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:01:40.150Z,1429286500.150 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:01:43.971Z,1429286503.971 [NAL9602](INFO): SBD MO Status=2, MOMSN=52461, MT Status=2, MTMSN=0
2015-04-17T16:01:43.971Z,1429286503.971 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:01:46.150Z,1429286506.150 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:01:46.150Z,1429286506.150 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:01:46.150Z,1429286506.150 [BPC1](ERROR): Communications Fault
2015-04-17T16:01:46.172Z,1429286506.172 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:01:47.429Z,1429286507.429 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:01:47.429Z,1429286507.429 [BPC1] No Fault, FailCount= 1
2015-04-17T16:01:51.475Z,1429286511.475 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:01:51.475Z,1429286511.475 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:01:51.475Z,1429286511.475 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:01:51.915Z,1429286511.915 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:01:52.049Z,1429286512.049 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:01:53.321Z,1429286513.321 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:01:53.321Z,1429286513.321 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:02:03.506Z,1429286523.506 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:02:03.506Z,1429286523.506 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:02:03.506Z,1429286523.506 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:02:03.919Z,1429286523.919 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:02:04.047Z,1429286524.047 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:02:05.272Z,1429286525.272 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:02:05.272Z,1429286525.272 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:02:15.558Z,1429286535.558 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:02:15.558Z,1429286535.558 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:02:15.558Z,1429286535.558 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:02:16.004Z,1429286536.004 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:02:16.005Z,1429286536.005 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:02:16.126Z,1429286536.126 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:02:21.922Z,1429286541.922 [NAL9602](INFO): SBD MO Status=2, MOMSN=52461, MT Status=2, MTMSN=0
2015-04-17T16:02:21.922Z,1429286541.922 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:02:31.489Z,1429286551.489 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T16:03:31.862Z,1429286611.862 [NAL9602](INFO): SBD MO Status=2, MOMSN=52461, MT Status=2, MTMSN=0
2015-04-17T16:03:31.862Z,1429286611.862 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:03:58.724Z,1429286638.724 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=52461, MT Status=1, MTMSN=74
2015-04-17T16:03:58.785Z,1429286638.785 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0153.lzma
2015-04-17T16:03:58.786Z,1429286638.786 [NAL9602](INFO): Packets left to send: 2
2015-04-17T16:03:58.787Z,1429286638.787 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0153.lzma.parts/0002.sbd
2015-04-17T16:03:59.315Z,1429286639.315 [NAL9602](INFO): Received command:echo 1 > /dev/loadA2
2015-04-17T16:03:59.375Z,1429286639.375 [CommandLine](IMPORTANT): got command 
2015-04-17T16:03:59.375Z,1429286639.375 [CommandLine](FAULT): Incomplete syntax. Try: help
2015-04-17T16:04:07.762Z,1429286647.762 [CommandLine](IMPORTANT): got command report
2015-04-17T16:04:13.861Z,1429286653.861 [CommandLine](IMPORTANT): got command report mod NAL9602.sigQuality
2015-04-17T16:04:14.187Z,1429286654.187 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T16:04:17.819Z,1429286657.819 [CommandLine](IMPORTANT): got command configSet list
2015-04-17T16:04:17.820Z,1429286657.820 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2015-04-17T16:04:17.821Z,1429286657.821 [CommandLine](IMPORTANT): CBIT.runElevOffsetCalc=0 bool;

2015-04-17T16:04:17.821Z,1429286657.821 [CommandLine](IMPORTANT): CBIT.runFaultClassifier=1 bool;

2015-04-17T16:04:17.821Z,1429286657.821 [CommandLine](IMPORTANT): DataOverHttps.loadAtStartup=0 bool;

2015-04-17T16:04:17.821Z,1429286657.821 [CommandLine](IMPORTANT): Express linearApproximation NAL9602.sigQuality 0.500000 count;

2015-04-17T16:04:17.821Z,1429286657.821 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=389 cubic_centimeter;

2015-04-17T16:04:17.821Z,1429286657.821 [CommandLine](IMPORTANT): VerticalControl.massDefault=7.5 millimeter;

2015-04-17T16:04:17.821Z,1429286657.821 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold=1.5 meter;

2015-04-17T16:04:20.123Z,1429286660.123 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=52462, MT Status=1, MTMSN=75
2015-04-17T16:04:20.181Z,1429286660.181 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0153.lzma
2015-04-17T16:04:20.182Z,1429286660.182 [NAL9602](INFO): Packets left to send: 1
2015-04-17T16:04:20.183Z,1429286660.183 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0153.lzma.parts/0001.sbd
2015-04-17T16:04:20.724Z,1429286660.724 [NAL9602](INFO): Received command:report m NAL9602.sigQuality
2015-04-17T16:04:21.201Z,1429286661.201 [CommandLine](IMPORTANT): got command report mod NAL9602.sigQuality
2015-04-17T16:04:24.746Z,1429286664.746 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:04:45.629Z,1429286685.629 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:04:45.629Z,1429286685.629 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:04:48.124Z,1429286688.124 [NAL9602](INFO): SBD MO Status=2, MOMSN=52463, MT Status=2, MTMSN=0
2015-04-17T16:04:48.125Z,1429286688.125 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:04:52.389Z,1429286692.389 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T16:04:56.003Z,1429286696.003 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:04:56.003Z,1429286696.003 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:04:56.003Z,1429286696.003 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:04:56.440Z,1429286696.440 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:04:56.567Z,1429286696.567 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:04:57.837Z,1429286697.837 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:04:57.837Z,1429286697.837 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:05:01.581Z,1429286701.581 [NAL9602](INFO): SBD MO Status=1, MOMSN=52463, MT Status=0, MTMSN=0
2015-04-17T16:05:01.639Z,1429286701.639 [NAL9602](INFO): Sent 139 bytes from file Logs/20150417T022810/Express0153.lzma
2015-04-17T16:05:01.639Z,1429286701.639 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:05:01.640Z,1429286701.640 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0153.lzma.parts/0000.sbd
2015-04-17T16:05:01.640Z,1429286701.640 [NAL9602](INFO): Completed sending Logs/20150417T022810/Express0153.lzma
2015-04-17T16:05:08.247Z,1429286708.247 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:05:08.247Z,1429286708.247 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:05:08.247Z,1429286708.247 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:05:08.669Z,1429286708.669 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:05:08.799Z,1429286708.799 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:05:10.069Z,1429286710.069 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:05:10.069Z,1429286710.069 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:05:11.361Z,1429286711.361 [NAL9602](INFO): SBD MO Status=1, MOMSN=52464, MT Status=0, MTMSN=0
2015-04-17T16:05:11.414Z,1429286711.414 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0157.lzma
2015-04-17T16:05:11.414Z,1429286711.414 [NAL9602](INFO): Packets left to send: 4
2015-04-17T16:05:11.416Z,1429286711.416 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0157.lzma.parts/0004.sbd
2015-04-17T16:05:20.475Z,1429286720.475 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:05:20.475Z,1429286720.475 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:05:20.475Z,1429286720.475 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:05:20.927Z,1429286720.927 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:05:20.928Z,1429286720.928 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:05:21.068Z,1429286721.068 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:05:24.517Z,1429286724.517 [NAL9602](INFO): SBD MO Status=1, MOMSN=52465, MT Status=0, MTMSN=0
2015-04-17T16:05:24.574Z,1429286724.574 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0157.lzma
2015-04-17T16:05:24.575Z,1429286724.575 [NAL9602](INFO): Packets left to send: 3
2015-04-17T16:05:24.576Z,1429286724.576 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0157.lzma.parts/0003.sbd
2015-04-17T16:05:30.836Z,1429286730.836 [CBIT](DEBUG): EFC running - data check-sum false
2015-04-17T16:05:31.232Z,1429286731.232 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:05:31.233Z,1429286731.233 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:05:31.233Z,1429286731.233 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:05:31.233Z,1429286731.233 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:05:31.233Z,1429286731.233 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:05:31.650Z,1429286731.650 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 7.256434 min 
2015-04-17T16:05:31.651Z,1429286731.651 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:05:31.651Z,1429286731.651 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:05:31.651Z,1429286731.651 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:05:31.651Z,1429286731.651 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:05:36.511Z,1429286736.511 [NAL9602](INFO): SBD MO Status=1, MOMSN=52466, MT Status=0, MTMSN=0
2015-04-17T16:05:36.561Z,1429286736.561 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0157.lzma
2015-04-17T16:05:36.561Z,1429286736.561 [NAL9602](INFO): Packets left to send: 2
2015-04-17T16:05:36.562Z,1429286736.562 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0157.lzma.parts/0002.sbd
2015-04-17T16:05:41.501Z,1429286741.501 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,FFFB%37
$B12,0A,FFFC%35
$B13,0A,FFFD%33
$B14,0A,FFFC%33
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,02A8,03,0001,08,0B3B,09,3E6C,0A,FFFB,0B,FFFB%4B
$B11,0C,0001,0D,0062,0E,0054,0F,164E,10,1924,11,FFFF,12,FFFF%38
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3B
$B11,1A,0031,1B,458F,1C,0157%4D
$B12,02,000A,01,02A8,03,0001,08,0B33,09,3E6D,0A,FFFC,0B,FFFC%3E
$B12,0C,0001,0D,0063,0E,0056,0F,16A4,10,19A5,11,FFFF,12,FFFF%4E
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%38
$B12,1A,0031,1B,458C,1C,00F1%3F
$B13,02,000A,01,02A8,03,0001,08,0B3A,09,3E5B,0A,FFFD,0B,FFFD%48
$B13,0C,0001,0D,0059,0E,0054,0F,1647,10,1912,11,FFFF,12,FFFF%45
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%39
$B13,1A,0031,1B,458B,1C,00BF%4C
$B14,02,000A,01,02A8,03,0001,08,0B2B,09,3E5F,0A,FFFC,0B,FFFC%49
$B14,0C,0001,0D,0059,0E,0054,0F,1617,10,18F9,11,FFFF,12,FFFF%3A
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3E
$B14,1A,0031,1B,458B,1C,0089%4E
$B15,02,000A,01,02A8,03,0001,08,0B2D,09,3E75,0A,0000,0B,0000%3F
$B15,0C,0001,0D,005B,0E,0058,0F,174A,10,19B0,11,FFFF,12,FFFF%32
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3F
$B15,1A,0031,1B,458C,1C,00C2%3E
$B16,02,000A,01,02A8,03,0001,08,0B34,09,3E86,0A,0000,0B,0000%41
$B16,0C,0001,0D,005A,0E,0057,0F,16E6,10,199F,11,FFFF,12,FFFF%37
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3C
$B16,1A,0031,1B,458C,1C,00CA%4E
$B17,02,000A,01,02A8,03,0001,08,0B34,09,3E0F,0A,0000,0B,0000%38
$B17,0C,0001,0D,0063,0E,0054,0F,1643,10,19B0,11,FFFF,12,FFFF%3D
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3D
$B17,1A,0031,1B,458C,1C,011A%3C
$B18,02,000A,01,02A8,03,0001,08,0B2C,09,3D99,0A,0000,0B,0000%36
$B18,0C,0001,0D,0059,0E,0052,0F,1594,10,1905,11,FFFF,12,FFFF%43
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%32
$B18,1A,0031,1B,458C,1C,00FD%40

2015-04-17T16:05:41.501Z,1429286741.501 [BPC1](ERROR): Failed to parse bank A battery data
2015-04-17T16:05:41.501Z,1429286741.501 [BPC1] Data Fault, FailCount= 1
2015-04-17T16:05:41.501Z,1429286741.501 [BPC1](ERROR): Data Fault
2015-04-17T16:05:41.523Z,1429286741.523 [CBIT](ERROR): Data Fault in component: BPC1
2015-04-17T16:05:42.842Z,1429286742.842 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:05:42.842Z,1429286742.842 [BPC1] No Fault, FailCount= 1
2015-04-17T16:05:49.224Z,1429286749.224 [NAL9602](INFO): SBD MO Status=1, MOMSN=52467, MT Status=0, MTMSN=0
2015-04-17T16:05:49.279Z,1429286749.279 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0157.lzma
2015-04-17T16:05:49.279Z,1429286749.279 [NAL9602](INFO): Packets left to send: 1
2015-04-17T16:05:49.281Z,1429286749.281 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0157.lzma.parts/0001.sbd
2015-04-17T16:06:00.593Z,1429286760.593 [NAL9602](INFO): SBD MO Status=1, MOMSN=52468, MT Status=0, MTMSN=0
2015-04-17T16:06:00.642Z,1429286760.642 [NAL9602](INFO): Sent 42 bytes from file Logs/20150417T022810/Express0157.lzma
2015-04-17T16:06:00.643Z,1429286760.643 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:06:00.644Z,1429286760.644 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0157.lzma.parts/0000.sbd
2015-04-17T16:06:00.644Z,1429286760.644 [NAL9602](INFO): Completed sending Logs/20150417T022810/Express0157.lzma
2015-04-17T16:06:10.945Z,1429286770.945 [NAL9602](INFO): SBD MO Status=1, MOMSN=52469, MT Status=0, MTMSN=0
2015-04-17T16:06:11.002Z,1429286771.002 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0161.lzma
2015-04-17T16:06:11.002Z,1429286771.002 [NAL9602](INFO): Packets left to send: 3
2015-04-17T16:06:11.004Z,1429286771.004 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0161.lzma.parts/0003.sbd
2015-04-17T16:06:21.383Z,1429286781.383 [NAL9602](INFO): SBD MO Status=1, MOMSN=52470, MT Status=0, MTMSN=0
2015-04-17T16:06:21.438Z,1429286781.438 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0161.lzma
2015-04-17T16:06:21.439Z,1429286781.439 [NAL9602](INFO): Packets left to send: 2
2015-04-17T16:06:21.440Z,1429286781.440 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0161.lzma.parts/0002.sbd
2015-04-17T16:06:33.167Z,1429286793.167 [NAL9602](INFO): SBD MO Status=1, MOMSN=52471, MT Status=0, MTMSN=0
2015-04-17T16:06:33.225Z,1429286793.225 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0161.lzma
2015-04-17T16:06:33.225Z,1429286793.225 [NAL9602](INFO): Packets left to send: 1
2015-04-17T16:06:33.226Z,1429286793.226 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0161.lzma.parts/0001.sbd
2015-04-17T16:06:33.271Z,1429286793.271 [CBIT](INFO): Clearing failed count for component DVL_micro
2015-04-17T16:06:33.271Z,1429286793.271 [DVL_micro] No Fault, FailCount= 5
2015-04-17T16:06:33.681Z,1429286793.681 [DVL_micro](INFO): Initializing
2015-04-17T16:06:44.267Z,1429286804.267 [NAL9602](INFO): SBD MO Status=1, MOMSN=52472, MT Status=0, MTMSN=0
2015-04-17T16:06:44.318Z,1429286804.318 [NAL9602](INFO): Sent 97 bytes from file Logs/20150417T022810/Express0161.lzma
2015-04-17T16:06:44.318Z,1429286804.318 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:06:44.319Z,1429286804.319 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0161.lzma.parts/0000.sbd
2015-04-17T16:06:44.320Z,1429286804.320 [NAL9602](INFO): Completed sending Logs/20150417T022810/Express0161.lzma
2015-04-17T16:06:44.748Z,1429286804.748 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:06:44.748Z,1429286804.748 [DVL_micro] Communications Fault, FailCount= 1
2015-04-17T16:06:44.748Z,1429286804.748 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:06:45.044Z,1429286805.044 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:06:45.504Z,1429286805.504 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:06:47.957Z,1429286807.957 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:06:48.413Z,1429286808.413 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:06:48.413Z,1429286808.413 [DVL_micro] No Fault, FailCount= 1
2015-04-17T16:06:48.833Z,1429286808.833 [DVL_micro](INFO): Initializing
2015-04-17T16:06:59.530Z,1429286819.530 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:06:59.531Z,1429286819.531 [DVL_micro] Communications Fault, FailCount= 2
2015-04-17T16:06:59.531Z,1429286819.531 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:06:59.581Z,1429286819.581 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:07:00.038Z,1429286820.038 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:07:00.480Z,1429286820.480 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:07:00.980Z,1429286820.980 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:07:00.980Z,1429286820.980 [DVL_micro] No Fault, FailCount= 2
2015-04-17T16:07:01.330Z,1429286821.330 [DVL_micro](INFO): Initializing
2015-04-17T16:07:11.958Z,1429286831.958 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:07:11.958Z,1429286831.958 [DVL_micro] Communications Fault, FailCount= 3
2015-04-17T16:07:11.958Z,1429286831.958 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:07:12.037Z,1429286832.037 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:07:12.505Z,1429286832.505 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:07:12.972Z,1429286832.972 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:07:13.446Z,1429286833.446 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:07:13.447Z,1429286833.447 [DVL_micro] No Fault, FailCount= 3
2015-04-17T16:07:13.828Z,1429286833.828 [DVL_micro](INFO): Initializing
2015-04-17T16:07:24.421Z,1429286844.421 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:07:24.421Z,1429286844.421 [DVL_micro] Communications Fault, FailCount= 4
2015-04-17T16:07:24.421Z,1429286844.421 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:07:24.518Z,1429286844.518 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:07:24.958Z,1429286844.958 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:07:25.381Z,1429286845.381 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:07:25.839Z,1429286845.839 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:07:25.839Z,1429286845.839 [DVL_micro] No Fault, FailCount= 4
2015-04-17T16:07:26.238Z,1429286846.238 [DVL_micro](INFO): Initializing
2015-04-17T16:07:27.913Z,1429286847.913 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:07:27.913Z,1429286847.913 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:07:27.913Z,1429286847.913 [BPC1](ERROR): Communications Fault
2015-04-17T16:07:27.975Z,1429286847.975 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:07:29.205Z,1429286849.205 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:07:29.205Z,1429286849.205 [BPC1] No Fault, FailCount= 1
2015-04-17T16:07:37.069Z,1429286857.069 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:07:37.069Z,1429286857.069 [DVL_micro] Communications Fault, FailCount= 5
2015-04-17T16:07:37.069Z,1429286857.069 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:07:37.125Z,1429286857.125 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:07:37.125Z,1429286857.125 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2015-04-17T16:07:37.584Z,1429286857.584 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:07:38.022Z,1429286858.022 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:07:38.025Z,1429286858.025 [NAL9602](INFO): SBD MO Status=2, MOMSN=52473, MT Status=2, MTMSN=0
2015-04-17T16:07:38.025Z,1429286858.025 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:07:42.351Z,1429286862.351 [Reporter](INFO): NAL9602.sigQuality 4 count
2015-04-17T16:07:50.159Z,1429286870.159 [NAL9602](INFO): SBD MO Status=1, MOMSN=52473, MT Status=0, MTMSN=0
2015-04-17T16:07:50.212Z,1429286870.212 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0165.lzma
2015-04-17T16:07:50.212Z,1429286870.212 [NAL9602](INFO): Packets left to send: 1
2015-04-17T16:07:50.214Z,1429286870.214 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0165.lzma.parts/0001.sbd
2015-04-17T16:07:50.951Z,1429286870.951 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:07:50.951Z,1429286870.951 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:08:01.082Z,1429286881.082 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:08:01.082Z,1429286881.082 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:08:01.082Z,1429286881.082 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:08:01.477Z,1429286881.477 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:08:01.598Z,1429286881.598 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:08:02.779Z,1429286882.779 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:08:02.779Z,1429286882.779 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:08:13.072Z,1429286893.072 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:08:13.072Z,1429286893.072 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:08:13.073Z,1429286893.073 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:08:13.471Z,1429286893.471 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:08:13.596Z,1429286893.596 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:08:14.863Z,1429286894.863 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:08:14.863Z,1429286894.863 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:08:24.990Z,1429286904.990 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:08:24.991Z,1429286904.991 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:08:24.991Z,1429286904.991 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:08:25.437Z,1429286905.437 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:08:25.437Z,1429286905.437 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:08:25.563Z,1429286905.563 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:08:44.171Z,1429286924.171 [NAL9602](INFO): SBD MO Status=2, MOMSN=52474, MT Status=2, MTMSN=0
2015-04-17T16:08:44.171Z,1429286924.171 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:08:48.435Z,1429286928.435 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T16:09:03.019Z,1429286943.019 [NAL9602](INFO): SBD MO Status=1, MOMSN=52474, MT Status=0, MTMSN=0
2015-04-17T16:09:03.065Z,1429286943.065 [NAL9602](INFO): Sent 275 bytes from file Logs/20150417T022810/Express0165.lzma
2015-04-17T16:09:03.065Z,1429286943.065 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:09:03.066Z,1429286943.066 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0165.lzma.parts/0000.sbd
2015-04-17T16:09:03.067Z,1429286943.067 [NAL9602](INFO): Completed sending Logs/20150417T022810/Express0165.lzma
2015-04-17T16:09:26.240Z,1429286966.240 [NAL9602](INFO): SBD MO Status=2, MOMSN=52475, MT Status=0, MTMSN=0
2015-04-17T16:09:26.240Z,1429286966.240 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:09:30.609Z,1429286970.609 [Reporter](INFO): NAL9602.sigQuality 4 count
2015-04-17T16:09:51.740Z,1429286991.740 [NAL9602](INFO): SBD MO Status=2, MOMSN=52475, MT Status=2, MTMSN=0
2015-04-17T16:09:51.740Z,1429286991.740 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:09:56.192Z,1429286996.192 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:10:10.450Z,1429287010.450 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T16:10:31.448Z,1429287031.448 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:10:31.449Z,1429287031.449 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:10:31.449Z,1429287031.449 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:10:31.449Z,1429287031.449 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:10:31.450Z,1429287031.450 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:10:31.873Z,1429287031.873 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 12.260270 min 
2015-04-17T16:10:31.873Z,1429287031.873 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:10:31.874Z,1429287031.874 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:10:31.874Z,1429287031.874 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:10:31.874Z,1429287031.874 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:10:36.056Z,1429287036.056 [BPC1](ERROR): B bank expecting battery number:1 and read number: uart read:X
$S,01,6694,02,00,04,5D%28
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,FFFC%36
$B12,0A,FFFC%35
$B13,0A,FFFD%33
$B14,0A,FFFD%34
$B15,0A,0000%37
$B16,0A,FFA7%42
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,5A83,02,00,04,5D%5A
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,02A8,03,0001,08,0B33,09,3E6E,0A,FFFC,0B,FFFC%3C
$B11,0C,0001,0D,0063,0E,0055,0F,1680,10,190C,11,FFFF,12,FFFF%34
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3B
$B11,1A,0031,1B,458C,1C,00C9%31
$B12,02,000A,01,02A8,03,0001,08,0B34,09,3E6A,0A,FFFC,0B,FFFC%3C
$B12,0C,0001,0D,0063,0E,0055,0F,1674,10,191E,11,FFFF,12,FFFF%3B
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%38
$B12,1A,0031,1B,458C,1C,0103%4A
$B13,02,000A,01,02A8,03,0001,08,0B34,09,3E65,0A,FFFD,0B,FFFD%49
$B13,0C,0001,0D,005B,0E,0057,0F,16F1,10,196F,11,FFFF,12,FFFF%3A
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%39
$B13,1A,0031,1B,458F,1C,0152%4A
$B14,02,000A,01,02A8,03,0001,08,0B2A,09,3E65,0A,FFFD,0B,FFFD%3A
$B14,0C,0001,0D,005A,0E,0054,0F,1640,10,18E1,11,FFFF,12,FFFF%4B
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3E
$B14,1A,0031,1B,458C,1C,014A%3A
$B15,02,000A,01,02A8,03,0001,08,0B36,09,3E71,0A,0000,0B,0000%48
$B15,0C,0001,0D,005A,0E,0056,0F,16B2,10,1941,11,FFFF,12,FFFF%4C
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3F
$B15,1A,0031,1B,458C,1C,0114%4B
$B16,02,000A,01,02A8,03,0001,08,0B2B,09,3DE5,0A,FF9E,0B,FFA2%46
$B16,0C,0001,0D,0057,0E,0053,0F,160A,10,1964,11,0D7E,12,0E11%49
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3C
$B16,1A,0031,1B,458F,1C,015E%38
$B17,02,000A,01,02A8,03,0001,08,0B34,09,3E10,0A,0000,0B,0000%4F
$B17,0C,0001,0D,0063,0E,0053,0F,15FA,10,194B,11,FFFF,12,FFFF%3D
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3D
$B17,1A,0031,1B,458B,1C,008E%31
$B18,02,000A,01,02A8,03,0001,08,0B3E,09,3DEB,0A,0000,0B,0000%36
$B18,0C,0001,0D,005A,0E,0056,0F,16A9,10,19F6,11,FFFF,12,FFFF%3C
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%32
$B18,1A,0031,1B,458B,1C,00AC%41

2015-04-17T16:10:36.057Z,1429287036.057 [BPC1](ERROR): Failed to parse bank B battery data
2015-04-17T16:10:36.057Z,1429287036.057 [BPC1] Data Fault, FailCount= 1
2015-04-17T16:10:36.057Z,1429287036.057 [BPC1](ERROR): Data Fault
2015-04-17T16:10:36.079Z,1429287036.079 [CBIT](ERROR): Data Fault in component: BPC1
2015-04-17T16:10:37.377Z,1429287037.377 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:10:37.377Z,1429287037.377 [BPC1] No Fault, FailCount= 1
2015-04-17T16:10:55.745Z,1429287055.745 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:10:55.745Z,1429287055.745 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:10:59.197Z,1429287059.197 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T16:11:05.815Z,1429287065.815 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:11:05.815Z,1429287065.815 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:11:05.815Z,1429287065.815 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:11:06.214Z,1429287066.214 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:11:06.336Z,1429287066.336 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:11:07.573Z,1429287067.573 [NAL9602](INFO): SBD MO Status=1, MOMSN=52475, MT Status=0, MTMSN=0
2015-04-17T16:11:07.626Z,1429287067.626 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0169.lzma
2015-04-17T16:11:07.626Z,1429287067.626 [NAL9602](INFO): Packets left to send: 2
2015-04-17T16:11:07.628Z,1429287067.628 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0169.lzma.parts/0002.sbd
2015-04-17T16:11:07.677Z,1429287067.677 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:11:07.694Z,1429287067.694 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:11:18.034Z,1429287078.034 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:11:18.034Z,1429287078.034 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:11:18.034Z,1429287078.034 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:11:18.435Z,1429287078.435 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:11:18.556Z,1429287078.556 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:11:19.837Z,1429287079.837 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:11:19.838Z,1429287079.838 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:11:30.033Z,1429287090.033 [NAL9602](INFO): SBD MO Status=2, MOMSN=52476, MT Status=2, MTMSN=0
2015-04-17T16:11:30.033Z,1429287090.033 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:11:30.064Z,1429287090.064 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:11:30.064Z,1429287090.064 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:11:30.064Z,1429287090.064 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:11:30.505Z,1429287090.505 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:11:30.509Z,1429287090.509 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:11:30.639Z,1429287090.639 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:11:34.569Z,1429287094.569 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:11:50.305Z,1429287110.305 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T16:12:06.062Z,1429287126.062 [NAL9602](INFO): SBD MO Status=2, MOMSN=52476, MT Status=2, MTMSN=0
2015-04-17T16:12:06.062Z,1429287126.062 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:12:10.493Z,1429287130.493 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:12:13.642Z,1429287133.642 [Reporter](INFO): NAL9602.sigQuality 4 count
2015-04-17T16:12:42.280Z,1429287162.280 [NAL9602](INFO): SBD MO Status=2, MOMSN=52476, MT Status=0, MTMSN=0
2015-04-17T16:12:42.280Z,1429287162.280 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:12:47.037Z,1429287167.037 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:13:14.390Z,1429287194.390 [NAL9602](INFO): SBD MO Status=2, MOMSN=52476, MT Status=0, MTMSN=0
2015-04-17T16:13:14.390Z,1429287194.390 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:13:31.134Z,1429287211.134 [NAL9602](INFO): SBD MO Status=1, MOMSN=52476, MT Status=0, MTMSN=0
2015-04-17T16:13:31.193Z,1429287211.193 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T022810/Express0169.lzma
2015-04-17T16:13:31.193Z,1429287211.193 [NAL9602](INFO): Packets left to send: 1
2015-04-17T16:13:31.194Z,1429287211.194 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0169.lzma.parts/0001.sbd
2015-04-17T16:13:40.896Z,1429287220.896 [NAL9602](INFO): SBD MO Status=1, MOMSN=52477, MT Status=0, MTMSN=0
2015-04-17T16:13:40.944Z,1429287220.944 [NAL9602](INFO): Sent 266 bytes from file Logs/20150417T022810/Express0169.lzma
2015-04-17T16:13:40.944Z,1429287220.944 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:13:40.945Z,1429287220.945 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0169.lzma.parts/0000.sbd
2015-04-17T16:13:40.945Z,1429287220.945 [NAL9602](INFO): Completed sending Logs/20150417T022810/Express0169.lzma
2015-04-17T16:13:51.672Z,1429287231.672 [NAL9602](INFO): SBD MO Status=1, MOMSN=52478, MT Status=0, MTMSN=0
2015-04-17T16:13:51.730Z,1429287231.730 [NAL9602](INFO): Sent 288 bytes from file Logs/20150417T022810/Express0173.lzma
2015-04-17T16:13:51.731Z,1429287231.731 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:13:51.733Z,1429287231.733 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0173.lzma.parts/0000.sbd
2015-04-17T16:13:51.733Z,1429287231.733 [NAL9602](INFO): Completed sending Logs/20150417T022810/Express0173.lzma
2015-04-17T16:13:58.686Z,1429287238.686 [NAL9602](INFO): SBD MO Status=1, MOMSN=52479, MT Status=0, MTMSN=0
2015-04-17T16:13:58.735Z,1429287238.735 [NAL9602](INFO): Sent 61 bytes from file Logs/20150417T022810/Express0177.lzma
2015-04-17T16:13:58.736Z,1429287238.736 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:13:58.738Z,1429287238.738 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T022810/Express0177.lzma.parts/0000.sbd
2015-04-17T16:13:58.738Z,1429287238.738 [NAL9602](INFO): Completed sending Logs/20150417T022810/Express0177.lzma
2015-04-17T16:14:01.917Z,1429287241.917 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:14:01.917Z,1429287241.917 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:14:12.170Z,1429287252.170 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:14:12.170Z,1429287252.170 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:14:12.170Z,1429287252.171 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:14:12.578Z,1429287252.578 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:14:12.701Z,1429287252.701 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:14:13.931Z,1429287253.931 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:14:13.931Z,1429287253.931 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:14:23.718Z,1429287263.718 [NAL9602](INFO): SBD MO Status=1, MOMSN=52480, MT Status=0, MTMSN=0
2015-04-17T16:14:23.778Z,1429287263.778 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T155520/Express0001.lzma
2015-04-17T16:14:23.778Z,1429287263.778 [NAL9602](INFO): Packets left to send: 2
2015-04-17T16:14:23.780Z,1429287263.780 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0001.lzma.parts/0002.sbd
2015-04-17T16:14:24.542Z,1429287264.542 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:14:24.543Z,1429287264.543 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:14:24.543Z,1429287264.543 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:14:26.986Z,1429287266.986 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:14:27.111Z,1429287267.111 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:14:28.299Z,1429287268.299 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:14:28.299Z,1429287268.299 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:14:34.779Z,1429287274.779 [NAL9602](INFO): SBD MO Status=1, MOMSN=52481, MT Status=0, MTMSN=0
2015-04-17T16:14:34.828Z,1429287274.828 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T155520/Express0001.lzma
2015-04-17T16:14:34.828Z,1429287274.828 [NAL9602](INFO): Packets left to send: 1
2015-04-17T16:14:34.829Z,1429287274.829 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0001.lzma.parts/0001.sbd
2015-04-17T16:14:38.706Z,1429287278.706 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:14:38.706Z,1429287278.706 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:14:38.706Z,1429287278.706 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:14:39.121Z,1429287279.121 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:14:39.121Z,1429287279.121 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:14:39.243Z,1429287279.243 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:14:50.595Z,1429287290.595 [NAL9602](INFO): SBD MO Status=2, MOMSN=52482, MT Status=2, MTMSN=0
2015-04-17T16:14:50.595Z,1429287290.595 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:14:54.811Z,1429287294.811 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T16:15:05.510Z,1429287305.510 [NAL9602](INFO): SBD MO Status=2, MOMSN=52482, MT Status=2, MTMSN=0
2015-04-17T16:15:05.510Z,1429287305.510 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:15:23.293Z,1429287323.293 [NAL9602](INFO): SBD MO Status=1, MOMSN=52482, MT Status=0, MTMSN=0
2015-04-17T16:15:23.346Z,1429287323.346 [NAL9602](INFO): Sent 61 bytes from file Logs/20150417T155520/Express0001.lzma
2015-04-17T16:15:23.347Z,1429287323.347 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:15:23.348Z,1429287323.348 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0001.lzma.parts/0000.sbd
2015-04-17T16:15:23.348Z,1429287323.348 [NAL9602](INFO): Completed sending Logs/20150417T155520/Express0001.lzma
2015-04-17T16:15:31.172Z,1429287331.172 [CBIT](DEBUG): EFC running - data check-sum false
2015-04-17T16:15:31.557Z,1429287331.557 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:15:31.557Z,1429287331.557 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:15:31.557Z,1429287331.557 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:15:31.558Z,1429287331.558 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:15:31.558Z,1429287331.558 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:15:31.968Z,1429287331.968 [NAL9602](INFO): SBD MO Status=1, MOMSN=52483, MT Status=0, MTMSN=0
2015-04-17T16:15:32.020Z,1429287332.020 [NAL9602](INFO): Sent 106 bytes from file Logs/20150417T155520/Express0005.lzma
2015-04-17T16:15:32.020Z,1429287332.020 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:15:32.022Z,1429287332.022 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0005.lzma.parts/0000.sbd
2015-04-17T16:15:32.022Z,1429287332.022 [NAL9602](INFO): Completed sending Logs/20150417T155520/Express0005.lzma
2015-04-17T16:15:32.069Z,1429287332.069 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 17.262045 min 
2015-04-17T16:15:32.070Z,1429287332.070 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:15:32.070Z,1429287332.070 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:15:32.070Z,1429287332.070 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:15:32.070Z,1429287332.070 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:15:41.197Z,1429287341.197 [NAL9602](INFO): SBD MO Status=0, MOMSN=52484, MT Status=0, MTMSN=0
2015-04-17T16:15:45.753Z,1429287345.753 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:15:55.287Z,1429287355.287 [NAL9602](INFO): SBD MO Status=1, MOMSN=52485, MT Status=0, MTMSN=0
2015-04-17T16:15:55.344Z,1429287355.344 [NAL9602](INFO): Sent 239 bytes from file Logs/20150417T155520/Courier0008.lzma
2015-04-17T16:15:55.344Z,1429287355.344 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:15:55.346Z,1429287355.346 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0008.lzma.parts/0000.sbd
2015-04-17T16:15:55.346Z,1429287355.346 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0008.lzma
2015-04-17T16:16:07.478Z,1429287367.478 [NAL9602](INFO): SBD MO Status=1, MOMSN=52486, MT Status=0, MTMSN=0
2015-04-17T16:16:07.527Z,1429287367.527 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T155520/Express0009.lzma
2015-04-17T16:16:07.527Z,1429287367.527 [NAL9602](INFO): Packets left to send: 3
2015-04-17T16:16:07.529Z,1429287367.529 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0009.lzma.parts/0003.sbd
2015-04-17T16:16:20.014Z,1429287380.014 [NAL9602](INFO): SBD MO Status=1, MOMSN=52487, MT Status=0, MTMSN=0
2015-04-17T16:16:20.063Z,1429287380.063 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T155520/Express0009.lzma
2015-04-17T16:16:20.063Z,1429287380.063 [NAL9602](INFO): Packets left to send: 2
2015-04-17T16:16:20.064Z,1429287380.064 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0009.lzma.parts/0002.sbd
2015-04-17T16:16:31.381Z,1429287391.381 [NAL9602](INFO): SBD MO Status=1, MOMSN=52488, MT Status=0, MTMSN=0
2015-04-17T16:16:31.428Z,1429287391.428 [NAL9602](INFO): Sent 332 bytes from file Logs/20150417T155520/Express0009.lzma
2015-04-17T16:16:31.428Z,1429287391.428 [NAL9602](INFO): Packets left to send: 1
2015-04-17T16:16:31.430Z,1429287391.430 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0009.lzma.parts/0001.sbd
2015-04-17T16:16:41.166Z,1429287401.166 [NAL9602](INFO): SBD MO Status=1, MOMSN=52489, MT Status=0, MTMSN=0
2015-04-17T16:16:41.215Z,1429287401.215 [NAL9602](INFO): Sent 56 bytes from file Logs/20150417T155520/Express0009.lzma
2015-04-17T16:16:41.216Z,1429287401.216 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:16:41.217Z,1429287401.217 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0009.lzma.parts/0000.sbd
2015-04-17T16:16:41.217Z,1429287401.217 [NAL9602](INFO): Completed sending Logs/20150417T155520/Express0009.lzma
2015-04-17T16:16:49.779Z,1429287409.779 [NAL9602](INFO): SBD MO Status=0, MOMSN=52490, MT Status=0, MTMSN=0
2015-04-17T16:16:49.868Z,1429287409.868 [Default:CheckIn:Read_Iridium] Stopped
2015-04-17T16:16:49.869Z,1429287409.869 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-04-17T16:16:49.869Z,1429287409.869 [Default:CheckIn] Stopped
2015-04-17T16:16:49.869Z,1429287409.869 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-04-17T16:16:49.869Z,1429287409.869 [Default:CheckIn:A.SetSpeed] Stopped
2015-04-17T16:16:49.869Z,1429287409.869 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-04-17T16:17:00.218Z,1429287420.218 [NAL9602](INFO): Powering down
2015-04-17T16:17:00.247Z,1429287420.247 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:17:10.247Z,1429287430.247 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:17:10.247Z,1429287430.247 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:17:25.243Z,1429287445.243 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T16:17:29.886Z,1429287449.886 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:17:29.886Z,1429287449.886 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:17:29.886Z,1429287449.886 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:17:30.249Z,1429287450.249 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:17:35.003Z,1429287455.003 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:17:40.249Z,1429287460.249 [CBIT](INFO): Clearing failed count for component DVL_micro
2015-04-17T16:17:40.250Z,1429287460.250 [DVL_micro] No Fault, FailCount= 5
2015-04-17T16:17:45.230Z,1429287465.230 [DVL_micro](INFO): Initializing
2015-04-17T16:17:45.305Z,1429287465.305 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:17:45.305Z,1429287465.305 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:18:00.227Z,1429287480.227 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:18:00.227Z,1429287480.227 [DVL_micro] Communications Fault, FailCount= 1
2015-04-17T16:18:00.227Z,1429287480.227 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:18:00.308Z,1429287480.308 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:18:04.888Z,1429287484.888 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:18:04.888Z,1429287484.888 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:18:04.888Z,1429287484.888 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:18:05.302Z,1429287485.302 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:18:05.355Z,1429287485.355 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:18:10.005Z,1429287490.005 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:18:10.351Z,1429287490.351 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:18:15.272Z,1429287495.272 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:18:15.272Z,1429287495.272 [DVL_micro] No Fault, FailCount= 1
2015-04-17T16:18:20.222Z,1429287500.222 [DVL_micro](INFO): Initializing
2015-04-17T16:18:20.242Z,1429287500.242 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:18:20.242Z,1429287500.242 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:18:20.242Z,1429287500.242 [BPC1](ERROR): Communications Fault
2015-04-17T16:18:20.263Z,1429287500.263 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:18:20.263Z,1429287500.263 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:18:20.263Z,1429287500.263 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:18:35.231Z,1429287515.231 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:18:35.231Z,1429287515.231 [DVL_micro] Communications Fault, FailCount= 2
2015-04-17T16:18:35.231Z,1429287515.231 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:18:35.257Z,1429287515.257 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:18:35.257Z,1429287515.257 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:18:35.257Z,1429287515.257 [BPC1] No Fault, FailCount= 1
2015-04-17T16:18:39.886Z,1429287519.886 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:18:39.886Z,1429287519.886 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:18:39.886Z,1429287519.886 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:18:40.320Z,1429287520.320 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:18:40.409Z,1429287520.409 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:18:40.409Z,1429287520.409 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:18:45.003Z,1429287525.003 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:18:45.337Z,1429287525.337 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:18:50.310Z,1429287530.310 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:18:50.310Z,1429287530.310 [DVL_micro] No Fault, FailCount= 2
2015-04-17T16:18:55.238Z,1429287535.238 [DVL_micro](INFO): Initializing
2015-04-17T16:19:15.215Z,1429287555.215 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:19:15.215Z,1429287555.215 [DVL_micro] Communications Fault, FailCount= 3
2015-04-17T16:19:15.215Z,1429287555.215 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:19:15.311Z,1429287555.311 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:19:20.306Z,1429287560.306 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:19:25.227Z,1429287565.227 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:19:30.244Z,1429287570.244 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:19:30.244Z,1429287570.244 [DVL_micro] No Fault, FailCount= 3
2015-04-17T16:19:35.221Z,1429287575.221 [DVL_micro](INFO): Initializing
2015-04-17T16:19:50.221Z,1429287590.221 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:19:50.221Z,1429287590.221 [DVL_micro] Communications Fault, FailCount= 4
2015-04-17T16:19:50.222Z,1429287590.222 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:19:50.299Z,1429287590.299 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:19:55.292Z,1429287595.292 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:20:00.217Z,1429287600.217 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:20:05.293Z,1429287605.293 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:20:05.293Z,1429287605.293 [DVL_micro] No Fault, FailCount= 4
2015-04-17T16:20:10.227Z,1429287610.227 [DVL_micro](INFO): Initializing
2015-04-17T16:20:25.224Z,1429287625.224 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:20:25.224Z,1429287625.224 [DVL_micro] Communications Fault, FailCount= 5
2015-04-17T16:20:25.224Z,1429287625.224 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:20:25.250Z,1429287625.250 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:20:25.250Z,1429287625.250 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2015-04-17T16:20:30.290Z,1429287630.290 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:20:35.222Z,1429287635.222 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:20:35.240Z,1429287635.240 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:20:35.240Z,1429287635.240 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:20:35.240Z,1429287635.240 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:20:35.240Z,1429287635.240 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:20:35.240Z,1429287635.240 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:20:40.312Z,1429287640.312 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 22.323835 min 
2015-04-17T16:20:40.313Z,1429287640.313 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:20:40.313Z,1429287640.313 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:20:40.313Z,1429287640.313 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:20:40.313Z,1429287640.313 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:21:10.298Z,1429287670.298 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:21:10.298Z,1429287670.298 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:21:29.886Z,1429287689.886 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:21:29.886Z,1429287689.886 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:21:29.886Z,1429287689.886 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:21:30.270Z,1429287690.270 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:21:35.003Z,1429287695.003 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:21:45.253Z,1429287705.253 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:21:45.253Z,1429287705.253 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:21:50.290Z,1429287710.290 [Default:CheckIn] Running Loop=1
2015-04-17T16:21:50.290Z,1429287710.290 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-04-17T16:21:50.290Z,1429287710.290 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-04-17T16:21:50.290Z,1429287710.290 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-04-17T16:21:50.290Z,1429287710.290 [Default:CheckIn:Read_GPS] Running Loop=1
2015-04-17T16:21:50.684Z,1429287710.684 [NAL9602](INFO): Powering up
2015-04-17T16:21:50.710Z,1429287710.710 [Reporter](INFO): NAL9602.sigQuality no_value
2015-04-17T16:21:59.962Z,1429287719.962 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:21:59.962Z,1429287719.962 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:21:59.962Z,1429287719.962 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:22:00.380Z,1429287720.380 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:22:00.550Z,1429287720.550 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:22:00.920Z,1429287720.920 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:22:01.753Z,1429287721.753 [NAL9602](INFO): NAL9602 initialized
2015-04-17T16:22:01.778Z,1429287721.778 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:22:01.779Z,1429287721.779 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:22:12.089Z,1429287732.089 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:22:12.089Z,1429287732.089 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:22:12.089Z,1429287732.089 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:22:12.504Z,1429287732.504 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:22:12.504Z,1429287732.504 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:22:12.625Z,1429287732.625 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:22:17.253Z,1429287737.253 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T16:22:25.906Z,1429287745.906 [Reporter](INFO): NAL9602.sigQuality 4 count
2015-04-17T16:22:33.528Z,1429287753.528 [NAL9602](INFO): SBD MO Status=0, MOMSN=52491, MT Status=0, MTMSN=0
2015-04-17T16:22:33.529Z,1429287753.529 [NAL9602](INFO): No messages in MT queue
2015-04-17T16:22:35.009Z,1429287755.009 [NAL9602](INFO): GPS fix at 20150417T162022: (36.802607, -121.787051)
2015-04-17T16:22:35.037Z,1429287755.037 [Default:CheckIn:Read_GPS] Stopped
2015-04-17T16:22:35.037Z,1429287755.037 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-04-17T16:22:38.098Z,1429287758.098 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:22:58.475Z,1429287778.475 [NAL9602](INFO): SBD MO Status=2, MOMSN=52492, MT Status=2, MTMSN=0
2015-04-17T16:22:58.475Z,1429287778.475 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:23:02.856Z,1429287782.856 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T16:23:10.053Z,1429287790.053 [NAL9602](INFO): SBD MO Status=1, MOMSN=52492, MT Status=0, MTMSN=0
2015-04-17T16:23:10.110Z,1429287790.110 [NAL9602](INFO): Sent 132 bytes from file Logs/20150417T155520/Courier0012.lzma
2015-04-17T16:23:10.110Z,1429287790.110 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:23:10.112Z,1429287790.112 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0012.lzma.parts/0000.sbd
2015-04-17T16:23:10.112Z,1429287790.112 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0012.lzma
2015-04-17T16:23:19.319Z,1429287799.319 [NAL9602](INFO): SBD MO Status=1, MOMSN=52493, MT Status=0, MTMSN=0
2015-04-17T16:23:19.374Z,1429287799.374 [NAL9602](INFO): Sent 251 bytes from file Logs/20150417T155520/Express0013.lzma
2015-04-17T16:23:19.374Z,1429287799.374 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:23:19.375Z,1429287799.375 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0013.lzma.parts/0000.sbd
2015-04-17T16:23:19.376Z,1429287799.376 [NAL9602](INFO): Completed sending Logs/20150417T155520/Express0013.lzma
2015-04-17T16:23:27.605Z,1429287807.605 [NAL9602](INFO): SBD MO Status=0, MOMSN=52494, MT Status=0, MTMSN=0
2015-04-17T16:23:27.683Z,1429287807.683 [Default:CheckIn:Read_Iridium] Stopped
2015-04-17T16:23:27.683Z,1429287807.683 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-04-17T16:23:27.683Z,1429287807.683 [Default:CheckIn] Stopped
2015-04-17T16:23:27.683Z,1429287807.683 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-04-17T16:23:27.683Z,1429287807.683 [Default:CheckIn:A.SetSpeed] Stopped
2015-04-17T16:23:27.684Z,1429287807.684 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-04-17T16:23:33.114Z,1429287813.114 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:23:33.114Z,1429287813.114 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:23:33.114Z,1429287813.114 [BPC1](ERROR): Communications Fault
2015-04-17T16:23:33.186Z,1429287813.186 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:23:38.087Z,1429287818.087 [NAL9602](INFO): Powering down
2015-04-17T16:23:38.117Z,1429287818.117 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:23:48.111Z,1429287828.111 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:23:48.111Z,1429287828.111 [BPC1] No Fault, FailCount= 1
2015-04-17T16:24:43.193Z,1429287883.193 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:24:43.194Z,1429287883.194 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:25:02.756Z,1429287902.756 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:25:02.756Z,1429287902.756 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:25:02.756Z,1429287902.756 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:25:03.167Z,1429287903.167 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:25:07.873Z,1429287907.873 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:25:18.120Z,1429287918.120 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:25:18.120Z,1429287918.120 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:25:32.758Z,1429287932.758 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:25:32.758Z,1429287932.758 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:25:32.759Z,1429287932.759 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:25:33.147Z,1429287933.147 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:25:33.148Z,1429287933.148 [CBIT](DEBUG): EFC running - data check-sum false
2015-04-17T16:25:37.875Z,1429287937.875 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:25:38.214Z,1429287938.214 [Depth_Keller](ERROR): Pressure reading out of range: 1830.480225 decibar
2015-04-17T16:25:38.231Z,1429287938.231 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:25:38.231Z,1429287938.231 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:25:38.232Z,1429287938.232 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:25:38.232Z,1429287938.232 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:25:38.232Z,1429287938.232 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:25:43.143Z,1429287943.143 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 27.371726 min 
2015-04-17T16:25:43.144Z,1429287943.144 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:25:43.144Z,1429287943.144 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:25:43.144Z,1429287943.144 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:25:43.144Z,1429287943.144 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:25:48.157Z,1429287948.157 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:25:48.157Z,1429287948.157 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:25:53.118Z,1429287953.118 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T16:26:07.757Z,1429287967.757 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:26:07.757Z,1429287967.757 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:26:07.757Z,1429287967.757 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:26:08.187Z,1429287968.187 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:26:08.187Z,1429287968.187 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:26:12.873Z,1429287972.873 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:26:13.218Z,1429287973.218 [Depth_Keller](ERROR): Pressure reading out of range: 1830.489868 decibar
2015-04-17T16:26:43.089Z,1429288003.089 [Depth_Keller](ERROR): Pressure reading out of range: -850.693054 decibar
2015-04-17T16:27:03.088Z,1429288023.088 [Depth_Keller](ERROR): Pressure reading out of range: 1830.506470 decibar
2015-04-17T16:27:23.087Z,1429288043.087 [Depth_Keller](ERROR): Pressure reading out of range: -850.609253 decibar
2015-04-17T16:27:33.094Z,1429288053.094 [Depth_Keller](ERROR): Pressure reading out of range: 1830.487183 decibar
2015-04-17T16:28:28.141Z,1429288108.141 [Default:CheckIn] Running Loop=1
2015-04-17T16:28:28.141Z,1429288108.141 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-04-17T16:28:28.141Z,1429288108.141 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-04-17T16:28:28.141Z,1429288108.141 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-04-17T16:28:28.141Z,1429288108.141 [Default:CheckIn:Read_GPS] Running Loop=1
2015-04-17T16:28:28.539Z,1429288108.539 [NAL9602](INFO): Powering up
2015-04-17T16:28:28.598Z,1429288108.598 [Reporter](INFO): NAL9602.sigQuality no_value
2015-04-17T16:28:37.999Z,1429288117.999 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:28:37.999Z,1429288117.999 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:28:38.860Z,1429288118.860 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:28:39.683Z,1429288119.683 [NAL9602](INFO): NAL9602 initialized
2015-04-17T16:28:48.344Z,1429288128.344 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:28:48.344Z,1429288128.344 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:28:48.345Z,1429288128.345 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:28:48.800Z,1429288128.800 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:28:48.928Z,1429288128.928 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:28:50.188Z,1429288130.188 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:28:50.188Z,1429288130.188 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:28:51.047Z,1429288131.047 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T16:29:00.448Z,1429288140.448 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:29:00.448Z,1429288140.448 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:29:00.448Z,1429288140.448 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:29:00.841Z,1429288140.841 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:29:00.966Z,1429288140.966 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:29:02.188Z,1429288142.188 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:29:02.188Z,1429288142.188 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:29:09.382Z,1429288149.382 [NAL9602](INFO): SBD MO Status=2, MOMSN=52495, MT Status=2, MTMSN=0
2015-04-17T16:29:09.382Z,1429288149.382 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:29:12.480Z,1429288152.480 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:29:12.480Z,1429288152.480 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:29:12.480Z,1429288152.480 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:29:12.905Z,1429288152.905 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:29:12.906Z,1429288152.906 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:29:13.037Z,1429288153.037 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:29:13.864Z,1429288153.864 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:29:25.846Z,1429288165.846 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:29:37.881Z,1429288177.881 [NAL9602](INFO): SBD MO Status=0, MOMSN=52495, MT Status=0, MTMSN=0
2015-04-17T16:29:37.881Z,1429288177.881 [NAL9602](INFO): No messages in MT queue
2015-04-17T16:29:39.215Z,1429288179.215 [NAL9602](INFO): GPS fix at 20150417T162726: (36.802592, -121.787064)
2015-04-17T16:29:39.272Z,1429288179.272 [Default:CheckIn:Read_GPS] Stopped
2015-04-17T16:29:39.272Z,1429288179.272 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-04-17T16:29:42.156Z,1429288182.156 [Reporter](INFO): NAL9602.sigQuality 5 count
2015-04-17T16:29:56.700Z,1429288196.700 [NAL9602](INFO): SBD MO Status=1, MOMSN=52496, MT Status=0, MTMSN=0
2015-04-17T16:29:56.749Z,1429288196.749 [NAL9602](INFO): Sent 80 bytes from file Logs/20150417T155520/Courier0016.lzma
2015-04-17T16:29:56.749Z,1429288196.749 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:29:56.751Z,1429288196.751 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0016.lzma.parts/0000.sbd
2015-04-17T16:29:56.751Z,1429288196.751 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0016.lzma
2015-04-17T16:30:25.492Z,1429288225.492 [CBIT](INFO): Clearing failed count for component DVL_micro
2015-04-17T16:30:25.492Z,1429288225.492 [DVL_micro] No Fault, FailCount= 5
2015-04-17T16:30:25.886Z,1429288225.886 [DVL_micro](INFO): Initializing
2015-04-17T16:30:26.316Z,1429288226.316 [NAL9602](INFO): SBD MO Status=2, MOMSN=52497, MT Status=2, MTMSN=0
2015-04-17T16:30:26.316Z,1429288226.316 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:30:31.000Z,1429288231.000 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T16:30:36.503Z,1429288236.503 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:30:36.503Z,1429288236.503 [DVL_micro] Communications Fault, FailCount= 1
2015-04-17T16:30:36.503Z,1429288236.503 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:30:36.571Z,1429288236.571 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:30:36.995Z,1429288236.995 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:30:37.453Z,1429288237.453 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:30:37.953Z,1429288237.953 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:30:37.953Z,1429288237.953 [DVL_micro] No Fault, FailCount= 1
2015-04-17T16:30:38.325Z,1429288238.325 [DVL_micro](INFO): Initializing
2015-04-17T16:30:38.364Z,1429288238.364 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:30:38.364Z,1429288238.364 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:30:38.364Z,1429288238.364 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:30:38.365Z,1429288238.365 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:30:38.365Z,1429288238.365 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:30:38.774Z,1429288238.774 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 32.375072 min 
2015-04-17T16:30:38.774Z,1429288238.774 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:30:38.775Z,1429288238.775 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:30:38.775Z,1429288238.775 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:30:38.775Z,1429288238.775 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:30:48.955Z,1429288248.955 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:30:48.955Z,1429288248.955 [DVL_micro] Communications Fault, FailCount= 2
2015-04-17T16:30:48.955Z,1429288248.955 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:30:49.007Z,1429288249.007 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:30:49.490Z,1429288249.490 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:30:49.969Z,1429288249.969 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:30:50.455Z,1429288250.455 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:30:50.455Z,1429288250.455 [DVL_micro] No Fault, FailCount= 2
2015-04-17T16:30:50.860Z,1429288250.860 [DVL_micro](INFO): Initializing
2015-04-17T16:30:52.156Z,1429288252.156 [NAL9602](INFO): SBD MO Status=2, MOMSN=52497, MT Status=2, MTMSN=0
2015-04-17T16:30:52.156Z,1429288252.156 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:31:01.318Z,1429288261.318 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:31:01.318Z,1429288261.318 [DVL_micro] Communications Fault, FailCount= 3
2015-04-17T16:31:01.318Z,1429288261.318 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:31:01.386Z,1429288261.386 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:31:01.821Z,1429288261.821 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:31:02.256Z,1429288262.256 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:31:02.721Z,1429288262.721 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:31:02.721Z,1429288262.721 [DVL_micro] No Fault, FailCount= 3
2015-04-17T16:31:03.106Z,1429288263.106 [DVL_micro](INFO): Initializing
2015-04-17T16:31:07.840Z,1429288267.840 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:31:07.840Z,1429288267.840 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:31:07.840Z,1429288267.840 [BPC1](ERROR): Communications Fault
2015-04-17T16:31:07.862Z,1429288267.862 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:31:09.134Z,1429288269.134 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:31:09.134Z,1429288269.134 [BPC1] No Fault, FailCount= 1
2015-04-17T16:31:13.832Z,1429288273.832 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:31:13.832Z,1429288273.832 [DVL_micro] Communications Fault, FailCount= 4
2015-04-17T16:31:13.832Z,1429288273.832 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:31:13.889Z,1429288273.889 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:31:14.337Z,1429288274.337 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:31:14.775Z,1429288274.775 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:31:15.233Z,1429288275.233 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:31:15.233Z,1429288275.233 [DVL_micro] No Fault, FailCount= 4
2015-04-17T16:31:15.631Z,1429288275.631 [DVL_micro](INFO): Initializing
2015-04-17T16:31:19.873Z,1429288279.873 [NAL9602](INFO): SBD MO Status=2, MOMSN=52497, MT Status=2, MTMSN=0
2015-04-17T16:31:19.873Z,1429288279.873 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:31:24.329Z,1429288284.329 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:31:27.815Z,1429288287.815 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:31:27.815Z,1429288287.815 [DVL_micro] Communications Fault, FailCount= 5
2015-04-17T16:31:27.815Z,1429288287.815 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:31:27.872Z,1429288287.872 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:31:27.872Z,1429288287.872 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2015-04-17T16:31:28.310Z,1429288288.310 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:31:28.741Z,1429288288.741 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:31:42.620Z,1429288302.620 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:31:42.620Z,1429288302.620 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:31:52.896Z,1429288312.896 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:31:52.896Z,1429288312.896 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:31:52.900Z,1429288312.900 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:31:53.301Z,1429288313.301 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:31:53.421Z,1429288313.421 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:31:54.643Z,1429288314.643 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:31:54.647Z,1429288314.647 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:32:03.894Z,1429288323.894 [NAL9602](INFO): SBD MO Status=2, MOMSN=52497, MT Status=2, MTMSN=0
2015-04-17T16:32:03.894Z,1429288323.894 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:32:04.821Z,1429288324.821 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:32:04.821Z,1429288324.821 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:32:04.821Z,1429288324.821 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:32:05.277Z,1429288325.277 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:32:05.396Z,1429288325.396 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:32:06.681Z,1429288326.681 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:32:06.681Z,1429288326.681 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:32:16.996Z,1429288336.996 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:32:16.996Z,1429288336.996 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:32:16.996Z,1429288336.996 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:32:17.451Z,1429288337.451 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:32:17.451Z,1429288337.451 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:32:17.579Z,1429288337.579 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:32:21.427Z,1429288341.427 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T16:32:26.076Z,1429288346.076 [NAL9602](INFO): SBD MO Status=1, MOMSN=52497, MT Status=0, MTMSN=0
2015-04-17T16:32:26.124Z,1429288346.124 [NAL9602](INFO): Sent 267 bytes from file Logs/20150417T155520/Express0017.lzma
2015-04-17T16:32:26.125Z,1429288346.125 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:32:26.126Z,1429288346.126 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0017.lzma.parts/0000.sbd
2015-04-17T16:32:26.127Z,1429288346.127 [NAL9602](INFO): Completed sending Logs/20150417T155520/Express0017.lzma
2015-04-17T16:32:43.471Z,1429288363.471 [NAL9602](INFO): SBD MO Status=2, MOMSN=52498, MT Status=2, MTMSN=0
2015-04-17T16:32:43.471Z,1429288363.471 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:32:47.808Z,1429288367.808 [Reporter](INFO): NAL9602.sigQuality 5 count
2015-04-17T16:33:02.464Z,1429288382.464 [NAL9602](INFO): SBD MO Status=2, MOMSN=52498, MT Status=2, MTMSN=0
2015-04-17T16:33:02.465Z,1429288382.465 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:33:12.431Z,1429288392.431 [NAL9602](INFO): SBD MO Status=0, MOMSN=52498, MT Status=0, MTMSN=0
2015-04-17T16:33:12.528Z,1429288392.528 [Default:CheckIn:Read_Iridium] Stopped
2015-04-17T16:33:12.529Z,1429288392.529 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-04-17T16:33:12.529Z,1429288392.529 [Default:CheckIn] Stopped
2015-04-17T16:33:12.529Z,1429288392.529 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-04-17T16:33:12.529Z,1429288392.529 [Default:CheckIn:A.SetSpeed] Stopped
2015-04-17T16:33:12.529Z,1429288392.529 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-04-17T16:33:17.893Z,1429288397.893 [Depth_Keller](ERROR): Pressure reading out of range: 1830.479370 decibar
2015-04-17T16:33:22.894Z,1429288402.894 [NAL9602](INFO): Powering down
2015-04-17T16:33:22.946Z,1429288402.946 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:33:27.889Z,1429288407.889 [Depth_Keller](ERROR): Pressure reading out of range: 1830.516846 decibar
2015-04-17T16:33:37.888Z,1429288417.888 [Depth_Keller](ERROR): Pressure reading out of range: 1830.489868 decibar
2015-04-17T16:34:07.890Z,1429288447.890 [Depth_Keller](ERROR): Pressure reading out of range: 1830.530884 decibar
2015-04-17T16:34:47.955Z,1429288487.955 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:34:47.955Z,1429288487.955 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:35:07.557Z,1429288507.557 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:35:07.557Z,1429288507.557 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:35:07.558Z,1429288507.558 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:35:07.925Z,1429288507.925 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:35:12.675Z,1429288512.675 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:35:17.887Z,1429288517.887 [Depth_Keller](ERROR): Pressure reading out of range: 1830.508179 decibar
2015-04-17T16:35:22.920Z,1429288522.920 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:35:22.920Z,1429288522.920 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:35:37.967Z,1429288537.967 [CBIT](DEBUG): EFC running - data check-sum false
2015-04-17T16:35:42.556Z,1429288542.556 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:35:42.556Z,1429288542.556 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:35:42.556Z,1429288542.556 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:35:42.957Z,1429288542.957 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:35:42.957Z,1429288542.957 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:35:42.957Z,1429288542.957 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:35:42.957Z,1429288542.957 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:35:42.957Z,1429288542.957 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:35:42.967Z,1429288542.967 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:35:47.673Z,1429288547.673 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:35:48.057Z,1429288548.057 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 37.451684 min 
2015-04-17T16:35:48.057Z,1429288548.057 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:35:48.057Z,1429288548.057 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:35:48.057Z,1429288548.057 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:35:48.058Z,1429288548.058 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:35:52.891Z,1429288552.891 [Depth_Keller](ERROR): Pressure reading out of range: 1830.481934 decibar
2015-04-17T16:35:58.003Z,1429288558.003 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:35:58.003Z,1429288558.003 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:36:12.897Z,1429288572.897 [Depth_Keller](ERROR): Pressure reading out of range: 1830.495117 decibar
2015-04-17T16:36:17.558Z,1429288577.558 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:36:17.558Z,1429288577.558 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:36:17.558Z,1429288577.558 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:36:17.976Z,1429288577.976 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:36:17.976Z,1429288577.976 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:36:22.675Z,1429288582.675 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:36:27.891Z,1429288587.891 [Depth_Keller](ERROR): Pressure reading out of range: 1830.502075 decibar
2015-04-17T16:36:42.887Z,1429288602.887 [Depth_Keller](ERROR): Pressure reading out of range: 1830.518677 decibar
2015-04-17T16:36:52.886Z,1429288612.886 [Depth_Keller](ERROR): Pressure reading out of range: 1354.897827 decibar
2015-04-17T16:37:22.893Z,1429288642.893 [Depth_Keller](ERROR): Pressure reading out of range: 1830.534424 decibar
2015-04-17T16:38:02.885Z,1429288682.885 [Depth_Keller](ERROR): Pressure reading out of range: 1830.502075 decibar
2015-04-17T16:38:12.905Z,1429288692.905 [Default:CheckIn] Running Loop=1
2015-04-17T16:38:12.905Z,1429288692.905 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-04-17T16:38:12.905Z,1429288692.905 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-04-17T16:38:12.905Z,1429288692.905 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-04-17T16:38:12.931Z,1429288692.931 [Default:CheckIn:Read_GPS] Running Loop=1
2015-04-17T16:38:13.308Z,1429288693.308 [NAL9602](INFO): Powering up
2015-04-17T16:38:13.390Z,1429288693.390 [Reporter](INFO): NAL9602.sigQuality no_value
2015-04-17T16:38:23.519Z,1429288703.519 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:38:24.349Z,1429288704.349 [NAL9602](INFO): NAL9602 initialized
2015-04-17T16:38:34.505Z,1429288714.505 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:38:42.286Z,1429288722.286 [NAL9602](INFO): SBD MO Status=0, MOMSN=52499, MT Status=0, MTMSN=0
2015-04-17T16:38:42.286Z,1429288722.286 [NAL9602](INFO): No messages in MT queue
2015-04-17T16:38:46.235Z,1429288726.235 [NAL9602](INFO): GPS fix at 20150417T163633: (36.802612, -121.787102)
2015-04-17T16:38:46.275Z,1429288726.275 [Default:CheckIn:Read_GPS] Stopped
2015-04-17T16:38:46.276Z,1429288726.276 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-04-17T16:38:47.796Z,1429288727.796 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:38:47.796Z,1429288727.796 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:38:55.921Z,1429288735.921 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T16:38:58.972Z,1429288738.972 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:38:58.973Z,1429288738.973 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:38:58.973Z,1429288738.973 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:38:59.430Z,1429288739.430 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:38:59.550Z,1429288739.550 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:39:00.742Z,1429288740.742 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:39:00.743Z,1429288740.743 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:39:08.728Z,1429288748.728 [NAL9602](INFO): SBD MO Status=1, MOMSN=52500, MT Status=0, MTMSN=0
2015-04-17T16:39:08.777Z,1429288748.777 [NAL9602](INFO): Sent 132 bytes from file Logs/20150417T155520/Courier0020.lzma
2015-04-17T16:39:08.777Z,1429288748.777 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:39:08.778Z,1429288748.778 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0020.lzma.parts/0000.sbd
2015-04-17T16:39:08.779Z,1429288748.779 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0020.lzma
2015-04-17T16:39:12.020Z,1429288752.020 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:39:12.021Z,1429288752.021 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:39:12.021Z,1429288752.021 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:39:12.414Z,1429288752.414 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:39:12.534Z,1429288752.534 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:39:13.763Z,1429288753.763 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:39:13.763Z,1429288753.763 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:39:23.986Z,1429288763.986 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:39:23.986Z,1429288763.986 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:39:23.986Z,1429288763.986 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:39:24.377Z,1429288764.377 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:39:24.378Z,1429288764.378 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:39:24.497Z,1429288764.497 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:39:55.236Z,1429288795.236 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T16:40:15.711Z,1429288815.711 [NAL9602](INFO): SBD MO Status=2, MOMSN=52501, MT Status=2, MTMSN=0
2015-04-17T16:40:15.711Z,1429288815.711 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:40:20.395Z,1429288820.395 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:40:30.517Z,1429288830.517 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:40:43.229Z,1429288843.229 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:40:43.229Z,1429288843.229 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:40:43.229Z,1429288843.229 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:40:43.230Z,1429288843.230 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:40:43.230Z,1429288843.230 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:40:43.662Z,1429288843.662 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 42.456597 min 
2015-04-17T16:40:43.662Z,1429288843.662 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:40:43.662Z,1429288843.662 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:40:43.662Z,1429288843.662 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:40:43.662Z,1429288843.662 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:40:47.032Z,1429288847.032 [NAL9602](INFO): SBD MO Status=1, MOMSN=52501, MT Status=0, MTMSN=0
2015-04-17T16:40:47.085Z,1429288847.085 [NAL9602](INFO): Sent 276 bytes from file Logs/20150417T155520/Express0021.lzma
2015-04-17T16:40:47.085Z,1429288847.085 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:40:47.087Z,1429288847.087 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0021.lzma.parts/0000.sbd
2015-04-17T16:40:47.087Z,1429288847.087 [NAL9602](INFO): Completed sending Logs/20150417T155520/Express0021.lzma
2015-04-17T16:41:04.683Z,1429288864.683 [NAL9602](INFO): SBD MO Status=2, MOMSN=52502, MT Status=2, MTMSN=0
2015-04-17T16:41:04.683Z,1429288864.683 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:41:09.045Z,1429288869.045 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:41:09.593Z,1429288869.593 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2015-04-17T16:41:12.849Z,1429288872.849 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2015-04-17T16:41:23.524Z,1429288883.524 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:41:27.983Z,1429288887.983 [CBIT](INFO): Clearing failed count for component DVL_micro
2015-04-17T16:41:27.984Z,1429288887.984 [DVL_micro] No Fault, FailCount= 5
2015-04-17T16:41:28.383Z,1429288888.383 [DVL_micro](INFO): Initializing
2015-04-17T16:41:34.846Z,1429288894.846 [NAL9602](INFO): SBD MO Status=2, MOMSN=52502, MT Status=2, MTMSN=0
2015-04-17T16:41:34.847Z,1429288894.847 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:41:38.913Z,1429288898.913 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:41:38.913Z,1429288898.913 [DVL_micro] Communications Fault, FailCount= 1
2015-04-17T16:41:38.913Z,1429288898.913 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:41:38.990Z,1429288898.990 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:41:39.438Z,1429288899.438 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:41:39.532Z,1429288899.532 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T16:41:39.928Z,1429288899.928 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:41:40.582Z,1429288900.582 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:41:40.582Z,1429288900.582 [DVL_micro] No Fault, FailCount= 1
2015-04-17T16:41:40.955Z,1429288900.955 [DVL_micro](INFO): Initializing
2015-04-17T16:41:50.744Z,1429288910.744 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,5A57,02,00,04,5C%54
$C1,01,7F,02,00,03,7F,04,00,05,00,06,00,07,00%72
$B11,0A,FFFB%37
$B12,0A,FFFC%35
$B13,0A,FFFC%34
$B14,0A,FFFD%34
$B15,0A,0000%37
$B16,0A,FFAE%30
$B17,0A,0000%35
$S,01,5D0D,02,00,04,5C%27
$C1,01,7F,02,00,03,7F,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,02A8,03,0001,08,0B4A,09,3E6B,0A,FFFB,0B,FFFB%4E
$B11,0C,0001,0D,0062,0E,0054,0F,1616,10,1903,11,FFFF,12,FFFF%4B
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3B
$B11,1A,0031,1B,458B,1C,0091%42
$B12,02,000A,01,02A8,03,0001,08,0B2F,09,3E67,0A,FFFC,0B,FFFC%39
$B12,0C,0001,0D,0063,0E,0054,0F,1622,10,1906,11,FFFF,12,FFFF%4B
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%38
$B12,1A,0031,1B,458F,1C,0153%4A
$B13,02,000A,01,02A8,03,0001,08,0B53,09,3E67,0A,FFFC,0B,FFFC%4A
$B13,0C,0001,0D,0059,0E,0053,0F,15EF,10,1717,11,FFFF,12,FFFF%4A
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%39
$B13,1A,0031,1B,458B,1C,00AF%4F
$B14,02,000A,01,02A8,03,0001,08,0B38,09,3E67,0A,FFFD,0B,FFFD%40
$B14,0C,0001,0D,005A,0E,0056,0F,16C5,10,1974,11,FFFF,12,FFFF%4D
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3E
$B14,1A,0031,1B,458F,1C,0151%4E
$B15,02,000A,01,02A8,03,0001,08,0B31,09,3E77,0A,0000,0B,0000%49
$B15,0C,0001,0D,005A,0E,0056,0F,16C8,10,1997,11,FFFF,12,FFFF%4C
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3F
$B15,1A,0031,1B,458C,1C,00DC%48
$B16,02,000A,01,02A8,03,0001,08,0B48,09,3DCF,0A,FFA9,0B,FFAE%3C
$B16,0C,0001,0D,0056,0E,0052,0F,1594,10,1920,11,0EE2,12,0FCA%33
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3C
$B16,1A,0031,1B,458B,1C,00BA%4E
$B17,02,000A,01,02A8,03,0001,08,0B38,09,3E0C,0A,0000,0B,0000%31
$B17,0C,0001,0D,0063,0E,0053,0F,15CE,10,18F2,11,FFFF,12,FFFF%3F
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3D
$B17,1A,0031,1B,458C,1C,00DA%48
458B,1C,008E%31
$B18,02,000A,01,02A8,03,0001,08,0B48,09,3DEB,0A,0000,0B,0000%4C
$B18,0C,0001,0D,005A,0E,0056,0F,16A9,10,19F6,11,FFFF,12,FFFF%3C
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%32
$B18,1A,0031,1B,458B,1C,00AC%41

2015-04-17T16:41:50.744Z,1429288910.744 [BPC1](ERROR): Failed to parse bank A battery data
2015-04-17T16:41:50.744Z,1429288910.744 [BPC1] Data Fault, FailCount= 1
2015-04-17T16:41:50.744Z,1429288910.744 [BPC1](ERROR): Data Fault
2015-04-17T16:41:50.767Z,1429288910.767 [CBIT](ERROR): Data Fault in component: BPC1
2015-04-17T16:41:51.595Z,1429288911.595 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:41:51.595Z,1429288911.595 [DVL_micro] Communications Fault, FailCount= 2
2015-04-17T16:41:51.595Z,1429288911.595 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:41:51.639Z,1429288911.639 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:41:52.106Z,1429288912.106 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:41:52.157Z,1429288912.157 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:41:52.157Z,1429288912.157 [BPC1] No Fault, FailCount= 1
2015-04-17T16:41:52.518Z,1429288912.518 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:41:53.059Z,1429288913.059 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:41:53.059Z,1429288913.059 [DVL_micro] No Fault, FailCount= 2
2015-04-17T16:41:53.455Z,1429288913.455 [DVL_micro](INFO): Initializing
2015-04-17T16:41:54.361Z,1429288914.361 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:41:54.361Z,1429288914.361 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:41:56.821Z,1429288916.821 [NAL9602](INFO): SBD MO Status=0, MOMSN=52502, MT Status=0, MTMSN=0
2015-04-17T16:41:56.910Z,1429288916.910 [Default:CheckIn:Read_Iridium] Stopped
2015-04-17T16:41:56.910Z,1429288916.910 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-04-17T16:41:56.911Z,1429288916.911 [Default:CheckIn] Stopped
2015-04-17T16:41:56.911Z,1429288916.911 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-04-17T16:41:56.911Z,1429288916.911 [Default:CheckIn:A.SetSpeed] Stopped
2015-04-17T16:41:56.911Z,1429288916.911 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-04-17T16:42:06.928Z,1429288926.928 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:42:06.928Z,1429288926.928 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:42:06.928Z,1429288926.928 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:42:07.266Z,1429288927.266 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:42:07.266Z,1429288927.266 [DVL_micro] Communications Fault, FailCount= 3
2015-04-17T16:42:07.266Z,1429288927.266 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:42:07.268Z,1429288927.268 [NAL9602](INFO): Powering down
2015-04-17T16:42:07.392Z,1429288927.392 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:42:07.393Z,1429288927.393 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:42:07.399Z,1429288927.399 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:42:12.045Z,1429288932.045 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:42:12.453Z,1429288932.453 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:42:17.270Z,1429288937.270 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:42:22.319Z,1429288942.319 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:42:22.319Z,1429288942.319 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:42:22.319Z,1429288942.319 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:42:22.319Z,1429288942.319 [DVL_micro] No Fault, FailCount= 3
2015-04-17T16:42:27.272Z,1429288947.272 [DVL_micro](INFO): Initializing
2015-04-17T16:42:41.926Z,1429288961.926 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:42:41.926Z,1429288961.926 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:42:41.926Z,1429288961.926 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:42:42.286Z,1429288962.286 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:42:47.043Z,1429288967.043 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:42:47.380Z,1429288967.380 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:42:47.381Z,1429288967.381 [DVL_micro] Communications Fault, FailCount= 4
2015-04-17T16:42:47.381Z,1429288967.381 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:42:47.495Z,1429288967.495 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:42:52.335Z,1429288972.335 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:42:57.264Z,1429288977.264 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:42:57.339Z,1429288977.339 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:42:57.340Z,1429288977.340 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:43:02.289Z,1429288982.289 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:43:02.289Z,1429288982.289 [DVL_micro] No Fault, FailCount= 4
2015-04-17T16:43:07.279Z,1429288987.279 [DVL_micro](INFO): Initializing
2015-04-17T16:43:16.928Z,1429288996.928 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:43:16.928Z,1429288996.928 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:43:16.928Z,1429288996.928 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:43:17.366Z,1429288997.366 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:43:17.366Z,1429288997.366 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:43:22.045Z,1429289002.045 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:43:22.391Z,1429289002.391 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:43:22.391Z,1429289002.391 [DVL_micro] Communications Fault, FailCount= 5
2015-04-17T16:43:22.391Z,1429289002.391 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:43:22.455Z,1429289002.455 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:43:22.455Z,1429289002.455 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2015-04-17T16:43:27.337Z,1429289007.337 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:43:32.267Z,1429289012.267 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:45:42.319Z,1429289142.319 [CBIT](DEBUG): EFC running - data check-sum false
2015-04-17T16:45:47.321Z,1429289147.321 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:45:47.321Z,1429289147.321 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:45:47.321Z,1429289147.321 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:45:47.322Z,1429289147.322 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:45:47.322Z,1429289147.322 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:45:47.359Z,1429289147.359 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:45:47.359Z,1429289147.359 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:45:52.312Z,1429289152.312 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 47.524568 min 
2015-04-17T16:45:52.312Z,1429289152.312 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:45:52.313Z,1429289152.313 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:45:52.313Z,1429289152.313 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:45:52.313Z,1429289152.313 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:46:06.928Z,1429289166.928 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:46:06.928Z,1429289166.928 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:46:06.928Z,1429289166.928 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:46:07.379Z,1429289167.379 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:46:12.045Z,1429289172.045 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:46:22.319Z,1429289182.319 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:46:22.319Z,1429289182.319 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:46:41.926Z,1429289201.926 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:46:41.926Z,1429289201.926 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:46:41.926Z,1429289201.926 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:46:42.278Z,1429289202.278 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:46:47.043Z,1429289207.043 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:46:57.313Z,1429289217.313 [Default:CheckIn] Running Loop=1
2015-04-17T16:46:57.313Z,1429289217.313 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-04-17T16:46:57.313Z,1429289217.313 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-04-17T16:46:57.313Z,1429289217.313 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-04-17T16:46:57.313Z,1429289217.313 [Default:CheckIn:Read_GPS] Running Loop=1
2015-04-17T16:46:57.323Z,1429289217.323 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:46:57.323Z,1429289217.323 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:46:57.729Z,1429289217.729 [NAL9602](INFO): Powering up
2015-04-17T16:46:57.782Z,1429289217.782 [Reporter](INFO): NAL9602.sigQuality no_value
2015-04-17T16:47:07.487Z,1429289227.487 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:47:07.487Z,1429289227.487 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:47:07.487Z,1429289227.487 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:47:07.938Z,1429289227.938 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:47:07.938Z,1429289227.938 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:47:07.940Z,1429289227.940 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:47:08.068Z,1429289228.068 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:47:08.830Z,1429289228.830 [NAL9602](INFO): NAL9602 initialized
2015-04-17T16:47:14.032Z,1429289234.032 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:47:14.032Z,1429289234.032 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:47:14.033Z,1429289234.033 [BPC1](ERROR): Communications Fault
2015-04-17T16:47:14.055Z,1429289234.055 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:47:15.318Z,1429289235.318 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:47:15.318Z,1429289235.318 [BPC1] No Fault, FailCount= 1
2015-04-17T16:47:18.782Z,1429289238.782 [Reporter](INFO): NAL9602.sigQuality 5 count
2015-04-17T16:47:26.387Z,1429289246.387 [NAL9602](INFO): SBD MO Status=0, MOMSN=52503, MT Status=0, MTMSN=0
2015-04-17T16:47:26.387Z,1429289246.387 [NAL9602](INFO): No messages in MT queue
2015-04-17T16:47:29.933Z,1429289249.933 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T16:47:41.873Z,1429289261.873 [NAL9602](INFO): GPS fix at 20150417T164529: (36.802554, -121.787080)
2015-04-17T16:47:41.918Z,1429289261.918 [Default:CheckIn:Read_GPS] Stopped
2015-04-17T16:47:41.918Z,1429289261.918 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-04-17T16:47:53.311Z,1429289273.311 [Reporter](INFO): NAL9602.sigQuality 4 count
2015-04-17T16:47:53.861Z,1429289273.861 [BPC1](ERROR): Failed to parse message.
2015-04-17T16:47:53.861Z,1429289273.861 [BPC1](ERROR): Failed to parse bank A battery data
2015-04-17T16:47:53.861Z,1429289273.861 [BPC1] Data Fault, FailCount= 1
2015-04-17T16:47:53.861Z,1429289273.861 [BPC1](ERROR): Data Fault
2015-04-17T16:47:53.920Z,1429289273.920 [CBIT](ERROR): Data Fault in component: BPC1
2015-04-17T16:47:57.176Z,1429289277.176 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:47:57.176Z,1429289277.176 [BPC1] No Fault, FailCount= 1
2015-04-17T16:48:02.359Z,1429289282.359 [NAL9602](INFO): SBD MO Status=1, MOMSN=52504, MT Status=0, MTMSN=0
2015-04-17T16:48:02.416Z,1429289282.416 [NAL9602](INFO): Sent 131 bytes from file Logs/20150417T155520/Courier0024.lzma
2015-04-17T16:48:02.416Z,1429289282.416 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:48:02.418Z,1429289282.418 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0024.lzma.parts/0000.sbd
2015-04-17T16:48:02.418Z,1429289282.418 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0024.lzma
2015-04-17T16:48:13.267Z,1429289293.267 [NAL9602](INFO): SBD MO Status=1, MOMSN=52505, MT Status=0, MTMSN=0
2015-04-17T16:48:13.317Z,1429289293.317 [NAL9602](INFO): Sent 309 bytes from file Logs/20150417T155520/Express0025.lzma
2015-04-17T16:48:13.317Z,1429289293.317 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:48:13.319Z,1429289293.319 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0025.lzma.parts/0000.sbd
2015-04-17T16:48:13.319Z,1429289293.319 [NAL9602](INFO): Completed sending Logs/20150417T155520/Express0025.lzma
2015-04-17T16:48:21.606Z,1429289301.606 [NAL9602](INFO): SBD MO Status=0, MOMSN=52506, MT Status=0, MTMSN=0
2015-04-17T16:48:21.717Z,1429289301.717 [Default:CheckIn:Read_Iridium] Stopped
2015-04-17T16:48:21.717Z,1429289301.717 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-04-17T16:48:21.717Z,1429289301.717 [Default:CheckIn] Stopped
2015-04-17T16:48:21.718Z,1429289301.718 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-04-17T16:48:21.718Z,1429289301.718 [Default:CheckIn:A.SetSpeed] Stopped
2015-04-17T16:48:21.718Z,1429289301.718 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-04-17T16:48:32.067Z,1429289312.067 [NAL9602](INFO): Powering down
2015-04-17T16:48:32.096Z,1429289312.096 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:49:42.138Z,1429289382.138 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:49:42.139Z,1429289382.139 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:50:01.736Z,1429289401.736 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:50:01.736Z,1429289401.736 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:50:01.736Z,1429289401.736 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:50:02.131Z,1429289402.131 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:50:06.853Z,1429289406.853 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:50:17.098Z,1429289417.098 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:50:17.098Z,1429289417.098 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:50:36.734Z,1429289436.734 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:50:36.734Z,1429289436.734 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:50:36.734Z,1429289436.734 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:50:37.146Z,1429289437.146 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:50:41.851Z,1429289441.851 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:50:52.114Z,1429289452.114 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:50:52.114Z,1429289452.114 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:50:52.114Z,1429289452.114 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:50:52.115Z,1429289452.115 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:50:52.115Z,1429289452.115 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:50:52.153Z,1429289452.153 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:50:52.153Z,1429289452.153 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:50:57.109Z,1429289457.109 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 52.604641 min 
2015-04-17T16:50:57.110Z,1429289457.110 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:50:57.110Z,1429289457.110 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:50:57.110Z,1429289457.110 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:50:57.110Z,1429289457.110 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:51:06.736Z,1429289466.736 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:51:06.737Z,1429289466.737 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:51:06.737Z,1429289466.737 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:51:07.182Z,1429289467.182 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:51:07.182Z,1429289467.182 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:51:11.853Z,1429289471.853 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:53:07.115Z,1429289587.115 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:53:07.115Z,1429289587.115 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:53:07.115Z,1429289587.115 [BPC1](ERROR): Communications Fault
2015-04-17T16:53:07.136Z,1429289587.136 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:53:22.081Z,1429289602.081 [Default:CheckIn] Running Loop=1
2015-04-17T16:53:22.081Z,1429289602.081 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-04-17T16:53:22.081Z,1429289602.081 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-04-17T16:53:22.081Z,1429289602.081 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-04-17T16:53:22.081Z,1429289602.081 [Default:CheckIn:Read_GPS] Running Loop=1
2015-04-17T16:53:22.091Z,1429289602.091 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:53:22.091Z,1429289602.091 [BPC1] No Fault, FailCount= 1
2015-04-17T16:53:22.471Z,1429289602.471 [NAL9602](INFO): Powering up
2015-04-17T16:53:22.571Z,1429289602.571 [CBIT](INFO): Clearing failed count for component DVL_micro
2015-04-17T16:53:22.572Z,1429289602.572 [DVL_micro] No Fault, FailCount= 5
2015-04-17T16:53:22.574Z,1429289602.574 [Reporter](INFO): NAL9602.sigQuality no_value
2015-04-17T16:53:22.981Z,1429289602.981 [DVL_micro](INFO): Initializing
2015-04-17T16:53:32.683Z,1429289612.683 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:53:33.522Z,1429289613.522 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:53:33.523Z,1429289613.523 [DVL_micro] Communications Fault, FailCount= 1
2015-04-17T16:53:33.523Z,1429289613.523 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:53:33.524Z,1429289613.524 [NAL9602](INFO): NAL9602 initialized
2015-04-17T16:53:33.604Z,1429289613.604 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:53:34.066Z,1429289614.066 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:53:34.534Z,1429289614.534 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:53:35.080Z,1429289615.080 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:53:35.080Z,1429289615.080 [DVL_micro] No Fault, FailCount= 1
2015-04-17T16:53:35.455Z,1429289615.455 [DVL_micro](INFO): Initializing
2015-04-17T16:53:36.898Z,1429289616.898 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:53:36.898Z,1429289616.898 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:53:46.234Z,1429289626.234 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:53:46.234Z,1429289626.234 [DVL_micro] Communications Fault, FailCount= 2
2015-04-17T16:53:46.234Z,1429289626.234 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:53:46.286Z,1429289626.286 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:53:46.730Z,1429289626.730 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:53:47.169Z,1429289627.169 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:53:47.205Z,1429289627.205 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:53:47.205Z,1429289627.205 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:53:47.205Z,1429289627.205 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:53:47.651Z,1429289627.651 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:53:47.651Z,1429289627.651 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:53:47.651Z,1429289627.651 [DVL_micro] No Fault, FailCount= 2
2015-04-17T16:53:47.781Z,1429289627.781 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:53:48.138Z,1429289628.138 [DVL_micro](INFO): Initializing
2015-04-17T16:53:48.213Z,1429289628.213 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T16:53:49.064Z,1429289629.064 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:53:49.064Z,1429289629.064 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:53:58.873Z,1429289638.873 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:53:58.874Z,1429289638.874 [DVL_micro] Communications Fault, FailCount= 3
2015-04-17T16:53:58.874Z,1429289638.874 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:53:58.902Z,1429289638.902 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:53:59.377Z,1429289639.377 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:53:59.410Z,1429289639.410 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:53:59.410Z,1429289639.410 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:53:59.410Z,1429289639.410 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:53:59.795Z,1429289639.795 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:53:59.842Z,1429289639.842 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:53:59.971Z,1429289639.971 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:54:00.339Z,1429289640.339 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:54:00.339Z,1429289640.339 [DVL_micro] No Fault, FailCount= 3
2015-04-17T16:54:00.719Z,1429289640.719 [DVL_micro](INFO): Initializing
2015-04-17T16:54:01.208Z,1429289641.208 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:54:01.208Z,1429289641.208 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:54:08.098Z,1429289648.098 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:54:11.489Z,1429289651.489 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:54:11.490Z,1429289651.490 [DVL_micro] Communications Fault, FailCount= 4
2015-04-17T16:54:11.490Z,1429289651.490 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:54:11.524Z,1429289651.524 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:54:11.566Z,1429289651.566 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:54:11.566Z,1429289651.566 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:54:11.566Z,1429289651.566 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:54:12.013Z,1429289652.013 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:54:12.052Z,1429289652.052 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:54:12.052Z,1429289652.052 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:54:12.189Z,1429289652.189 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:54:12.519Z,1429289652.519 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:54:12.628Z,1429289652.628 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:54:13.025Z,1429289653.025 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T16:54:13.026Z,1429289653.026 [DVL_micro] No Fault, FailCount= 4
2015-04-17T16:54:13.417Z,1429289653.417 [DVL_micro](INFO): Initializing
2015-04-17T16:54:24.089Z,1429289664.089 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T16:54:24.089Z,1429289664.089 [DVL_micro] Communications Fault, FailCount= 5
2015-04-17T16:54:24.089Z,1429289664.089 [DVL_micro](ERROR): Communications Fault
2015-04-17T16:54:24.117Z,1429289664.117 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T16:54:24.118Z,1429289664.118 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2015-04-17T16:54:24.592Z,1429289664.592 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T16:54:25.026Z,1429289665.026 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T16:54:28.840Z,1429289668.840 [NAL9602](INFO): SBD MO Status=0, MOMSN=52507, MT Status=0, MTMSN=0
2015-04-17T16:54:28.840Z,1429289668.840 [NAL9602](INFO): No messages in MT queue
2015-04-17T16:54:30.127Z,1429289670.127 [NAL9602](INFO): GPS fix at 20150417T165217: (36.802622, -121.787087)
2015-04-17T16:54:30.167Z,1429289670.167 [Default:CheckIn:Read_GPS] Stopped
2015-04-17T16:54:30.167Z,1429289670.167 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-04-17T16:54:33.390Z,1429289673.390 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:54:37.303Z,1429289677.303 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T16:54:58.103Z,1429289698.103 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:55:10.627Z,1429289710.627 [NAL9602](INFO): SBD MO Status=2, MOMSN=52508, MT Status=2, MTMSN=0
2015-04-17T16:55:10.627Z,1429289710.627 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:55:14.943Z,1429289714.943 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:55:32.134Z,1429289732.134 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,FFFB%37
$B12,0A,FFFB%34
$B13,0A,FFFD%33
$B14,0A,FFFC%33
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,02A8,03,0001,08,0B54,09,3E6C,0A,FFFB,0B,FFFB%3B
$B11,0C,0001,0D,0062,0E,0054,0F,164D,10,1924,11,FFFF,12,FFFF%39
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3B
$B11,1A,0031,1B,458F,1C,0157%4D
$B12,02,000A,01,02A8,03,0001,08,0B44,09,3E6D,0A,FFFB,0B,FFFB%3E
$B12,0C,0001,0D,0063,0E,0056,0F,16A3,10,19A5,11,FFFF,12,FFFF%49
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%38
$B12,1A,0031,1B,458C,1C,00F1%3F
$B13,02,000A,01,02A8,03,0001,08,0B4D,09,3E5C,0A,FFFD,0B,FFFD%4B
$B13,0C,0001,0D,0059,0E,0054,0F,1646,10,1912,11,FFFF,12,FFFF%44
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%39
$B13,1A,0031,1B,458B,1C,00BF%4C
$B14,02,000A,01,02A8,03,0001,08,0B32,09,3E5F,0A,FFFD,0B,FFFC%3F
$B14,0C,0001,0D,0059,0E,0054,0F,1616,10,18F9,11,FFFF,12,FFFF%3B
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3E
$B14,1A,0031,1B,458B,1C,0089%4E
$B15,02,000A,01,02A8,03,0001,08,0B36,09,3E75,0A,0000,0B,0000%4C
$B15,0C,0001,0D,005B,0E,0058,0F,174A,10,19B0,11,FFFF,12,FFFF%32
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3F
$B15,1A,0031,1B,458C,1C,00C2%3E
$B16,02,000A,01,02A8,03,1717,08,0B46,09,3E86,0A,0000,0B,0000%45
$B16,0C,0001,0D,005A,0E,0057,0F,16E6,10,199F,11,FFFF,12,FFFF%37
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3C
$B16,1A,0031,1B,458C,1C,00CA%4E
$B17,02,000A,01,02A8,03,0001,08,0B3A,09,3E10,0A,0000,0B,0000%3A
$B17,0C,0001,0D,0063,0E,0054,0F,1643,10,19B0,11,FFFF,12,FFFF%3D
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%3D
$B17,1A,0031,1B,458C,1C,011A%3C
$B18,02,000A,01,02A8,03,0001,08,0B31,09,3D99,0A,0000,0B,0000%45
$B18,0C,0001,0D,0059,0E,0052,0F,1594,10,1905,11,FFFF,12,FFFF%43
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0003,18,1A90,19,3840%32
$B18,1A,0031,1B,458C,1C,00FD%40

2015-04-17T16:55:32.134Z,1429289732.134 [BPC1](ERROR): Failed to parse bank A battery data
2015-04-17T16:55:32.135Z,1429289732.135 [BPC1] Data Fault, FailCount= 1
2015-04-17T16:55:32.135Z,1429289732.135 [BPC1](ERROR): Data Fault
2015-04-17T16:55:32.198Z,1429289732.198 [CBIT](ERROR): Data Fault in component: BPC1
2015-04-17T16:55:33.434Z,1429289733.434 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:55:33.434Z,1429289733.434 [BPC1] No Fault, FailCount= 1
2015-04-17T16:55:42.413Z,1429289742.413 [CBIT](DEBUG): EFC running - data check-sum false
2015-04-17T16:55:52.330Z,1429289752.330 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T16:55:52.330Z,1429289752.330 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:55:52.330Z,1429289752.330 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T16:55:52.331Z,1429289752.331 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T16:55:52.331Z,1429289752.331 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T16:55:52.766Z,1429289752.766 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 57.607766 min 
2015-04-17T16:55:52.766Z,1429289752.766 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T16:55:52.767Z,1429289752.767 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:55:52.767Z,1429289752.767 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T16:55:52.767Z,1429289752.767 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T16:55:57.449Z,1429289757.449 [Reporter](INFO): NAL9602.sigQuality 3 count
2015-04-17T16:56:04.362Z,1429289764.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=52508, MT Status=0, MTMSN=0
2015-04-17T16:56:04.411Z,1429289764.411 [NAL9602](INFO): Sent 114 bytes from file Logs/20150417T155520/Courier0028.lzma
2015-04-17T16:56:04.411Z,1429289764.411 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:56:04.413Z,1429289764.413 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0028.lzma.parts/0000.sbd
2015-04-17T16:56:04.413Z,1429289764.413 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0028.lzma
2015-04-17T16:56:28.516Z,1429289788.516 [NAL9602](INFO): SBD MO Status=2, MOMSN=52509, MT Status=0, MTMSN=0
2015-04-17T16:56:28.517Z,1429289788.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:56:32.946Z,1429289792.946 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T16:56:41.913Z,1429289801.913 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:56:41.913Z,1429289801.913 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T16:56:52.078Z,1429289812.078 [NAL9602](INFO): SBD MO Status=2, MOMSN=52509, MT Status=2, MTMSN=0
2015-04-17T16:56:52.079Z,1429289812.079 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T16:56:52.148Z,1429289812.148 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:56:52.148Z,1429289812.148 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T16:56:52.148Z,1429289812.148 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:56:52.549Z,1429289812.549 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:56:52.670Z,1429289812.670 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:56:53.895Z,1429289813.895 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:56:53.895Z,1429289813.895 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T16:57:02.147Z,1429289822.147 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:57:02.148Z,1429289822.148 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:57:02.148Z,1429289822.148 [BPC1](ERROR): Communications Fault
2015-04-17T16:57:02.170Z,1429289822.170 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:57:03.446Z,1429289823.446 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:57:03.451Z,1429289823.451 [BPC1] No Fault, FailCount= 1
2015-04-17T16:57:03.910Z,1429289823.910 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:57:03.910Z,1429289823.910 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T16:57:03.910Z,1429289823.910 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:57:04.379Z,1429289824.379 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:57:04.520Z,1429289824.520 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:57:05.732Z,1429289825.732 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T16:57:05.732Z,1429289825.732 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T16:57:10.364Z,1429289830.364 [NAL9602](INFO): SBD MO Status=1, MOMSN=52509, MT Status=0, MTMSN=0
2015-04-17T16:57:10.418Z,1429289830.418 [NAL9602](INFO): Sent 272 bytes from file Logs/20150417T155520/Express0029.lzma
2015-04-17T16:57:10.418Z,1429289830.418 [NAL9602](INFO): Packets left to send: 0
2015-04-17T16:57:10.420Z,1429289830.420 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Express0029.lzma.parts/0000.sbd
2015-04-17T16:57:10.420Z,1429289830.420 [NAL9602](INFO): Completed sending Logs/20150417T155520/Express0029.lzma
2015-04-17T16:57:15.791Z,1429289835.791 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T16:57:15.791Z,1429289835.791 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T16:57:15.791Z,1429289835.791 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T16:57:16.240Z,1429289836.240 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T16:57:16.241Z,1429289836.241 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T16:57:16.367Z,1429289836.367 [Aanderaa_O2](INFO): Powering down
2015-04-17T16:57:21.357Z,1429289841.357 [NAL9602](INFO): SBD MO Status=0, MOMSN=52510, MT Status=0, MTMSN=0
2015-04-17T16:57:21.444Z,1429289841.444 [Default:CheckIn:Read_Iridium] Stopped
2015-04-17T16:57:21.445Z,1429289841.445 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-04-17T16:57:21.445Z,1429289841.445 [Default:CheckIn] Stopped
2015-04-17T16:57:21.445Z,1429289841.445 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-04-17T16:57:21.445Z,1429289841.445 [Default:CheckIn:A.SetSpeed] Stopped
2015-04-17T16:57:21.445Z,1429289841.445 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-04-17T16:57:31.796Z,1429289851.796 [NAL9602](INFO): Powering down
2015-04-17T16:57:31.826Z,1429289851.826 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T16:58:51.802Z,1429289931.802 [BPC1](ERROR): Failed to receive battery data
2015-04-17T16:58:51.802Z,1429289931.802 [BPC1] Communications Fault, FailCount= 1
2015-04-17T16:58:51.802Z,1429289931.802 [BPC1](ERROR): Communications Fault
2015-04-17T16:58:51.823Z,1429289931.823 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T16:59:06.826Z,1429289946.826 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T16:59:06.826Z,1429289946.826 [BPC1] No Fault, FailCount= 1
2015-04-17T16:59:46.830Z,1429289986.830 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T16:59:46.830Z,1429289986.830 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T17:00:06.461Z,1429290006.461 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:00:06.461Z,1429290006.461 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T17:00:06.461Z,1429290006.461 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:00:06.862Z,1429290006.862 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:00:11.578Z,1429290011.578 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:00:21.873Z,1429290021.873 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T17:00:21.873Z,1429290021.873 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T17:00:36.464Z,1429290036.464 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:00:36.464Z,1429290036.464 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T17:00:36.464Z,1429290036.464 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:00:36.864Z,1429290036.864 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:00:41.581Z,1429290041.581 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:00:51.868Z,1429290051.868 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T17:00:51.869Z,1429290051.869 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T17:00:56.817Z,1429290056.817 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T17:00:56.818Z,1429290056.818 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T17:00:56.818Z,1429290056.818 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T17:00:56.818Z,1429290056.818 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T17:00:56.818Z,1429290056.818 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T17:01:01.866Z,1429290061.866 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 62.683469 min 
2015-04-17T17:01:01.867Z,1429290061.867 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T17:01:01.867Z,1429290061.867 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T17:01:01.867Z,1429290061.867 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T17:01:01.867Z,1429290061.867 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T17:01:11.462Z,1429290071.462 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:01:11.462Z,1429290071.462 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T17:01:11.462Z,1429290071.462 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:01:11.866Z,1429290071.866 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:01:11.867Z,1429290071.867 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T17:01:16.575Z,1429290076.575 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:02:21.843Z,1429290141.843 [Default:CheckIn] Running Loop=1
2015-04-17T17:02:21.843Z,1429290141.843 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-04-17T17:02:21.843Z,1429290141.843 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-04-17T17:02:21.843Z,1429290141.843 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-04-17T17:02:21.843Z,1429290141.843 [Default:CheckIn:Read_GPS] Running Loop=1
2015-04-17T17:02:22.251Z,1429290142.251 [NAL9602](INFO): Powering up
2015-04-17T17:02:22.277Z,1429290142.277 [Reporter](INFO): NAL9602.sigQuality no_value
2015-04-17T17:02:23.565Z,1429290143.565 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T17:02:32.381Z,1429290152.381 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T17:02:33.187Z,1429290153.187 [NAL9602](INFO): NAL9602 initialized
2015-04-17T17:03:41.773Z,1429290221.773 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T17:03:41.773Z,1429290221.773 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T17:03:52.095Z,1429290232.095 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:03:52.096Z,1429290232.096 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T17:03:52.096Z,1429290232.096 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:03:52.512Z,1429290232.512 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:03:52.633Z,1429290232.633 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:03:53.847Z,1429290233.847 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T17:03:53.847Z,1429290233.847 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T17:04:04.254Z,1429290244.254 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:04:04.254Z,1429290244.254 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T17:04:04.254Z,1429290244.254 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:04:04.659Z,1429290244.659 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:04:04.784Z,1429290244.784 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:04:06.037Z,1429290246.037 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T17:04:06.037Z,1429290246.037 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T17:04:16.368Z,1429290256.368 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:04:16.368Z,1429290256.368 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T17:04:16.368Z,1429290256.368 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:04:16.826Z,1429290256.826 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:04:16.826Z,1429290256.826 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T17:04:16.951Z,1429290256.951 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:04:24.173Z,1429290264.173 [CBIT](INFO): Clearing failed count for component DVL_micro
2015-04-17T17:04:24.173Z,1429290264.173 [DVL_micro] No Fault, FailCount= 5
2015-04-17T17:04:24.562Z,1429290264.562 [DVL_micro](INFO): Initializing
2015-04-17T17:04:35.014Z,1429290275.014 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T17:04:35.014Z,1429290275.014 [DVL_micro] Communications Fault, FailCount= 1
2015-04-17T17:04:35.014Z,1429290275.014 [DVL_micro](ERROR): Communications Fault
2015-04-17T17:04:35.067Z,1429290275.067 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T17:04:35.532Z,1429290275.532 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T17:04:35.966Z,1429290275.966 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T17:04:36.469Z,1429290276.469 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T17:04:36.469Z,1429290276.469 [DVL_micro] No Fault, FailCount= 1
2015-04-17T17:04:36.861Z,1429290276.861 [DVL_micro](INFO): Initializing
2015-04-17T17:04:47.651Z,1429290287.651 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T17:04:47.651Z,1429290287.651 [DVL_micro] Communications Fault, FailCount= 2
2015-04-17T17:04:47.652Z,1429290287.652 [DVL_micro](ERROR): Communications Fault
2015-04-17T17:04:47.711Z,1429290287.711 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T17:04:48.159Z,1429290288.159 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T17:04:48.609Z,1429290288.609 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T17:04:49.057Z,1429290289.057 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T17:04:49.057Z,1429290289.057 [DVL_micro] No Fault, FailCount= 2
2015-04-17T17:04:49.457Z,1429290289.457 [DVL_micro](INFO): Initializing
2015-04-17T17:04:49.517Z,1429290289.517 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T17:05:00.165Z,1429290300.165 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T17:05:00.165Z,1429290300.165 [DVL_micro] Communications Fault, FailCount= 3
2015-04-17T17:05:00.165Z,1429290300.165 [DVL_micro](ERROR): Communications Fault
2015-04-17T17:05:00.229Z,1429290300.229 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T17:05:00.675Z,1429290300.675 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T17:05:01.153Z,1429290301.153 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T17:05:01.640Z,1429290301.640 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T17:05:01.640Z,1429290301.640 [DVL_micro] No Fault, FailCount= 3
2015-04-17T17:05:02.040Z,1429290302.040 [DVL_micro](INFO): Initializing
2015-04-17T17:05:05.909Z,1429290305.909 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T17:05:06.805Z,1429290306.805 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T17:05:12.647Z,1429290312.647 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T17:05:12.647Z,1429290312.647 [DVL_micro] Communications Fault, FailCount= 4
2015-04-17T17:05:12.647Z,1429290312.647 [DVL_micro](ERROR): Communications Fault
2015-04-17T17:05:12.675Z,1429290312.675 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T17:05:13.162Z,1429290313.162 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T17:05:13.638Z,1429290313.638 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T17:05:14.119Z,1429290314.119 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-04-17T17:05:14.119Z,1429290314.119 [DVL_micro] No Fault, FailCount= 4
2015-04-17T17:05:14.515Z,1429290314.515 [DVL_micro](INFO): Initializing
2015-04-17T17:05:25.206Z,1429290325.206 [DVL_micro](ERROR): No DVL communication! Re-initializing
2015-04-17T17:05:25.206Z,1429290325.206 [DVL_micro] Communications Fault, FailCount= 5
2015-04-17T17:05:25.206Z,1429290325.206 [DVL_micro](ERROR): Communications Fault
2015-04-17T17:05:25.270Z,1429290325.270 [CBIT](ERROR): Communications Fault in component: DVL_micro
2015-04-17T17:05:25.271Z,1429290325.271 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2015-04-17T17:05:25.713Z,1429290325.713 [DVL_micro](INFO): uninitialize:Powering down
2015-04-17T17:05:26.147Z,1429290326.147 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-04-17T17:05:29.598Z,1429290329.598 [NAL9602](INFO): SBD MO Status=2, MOMSN=52511, MT Status=2, MTMSN=0
2015-04-17T17:05:29.599Z,1429290329.599 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T17:05:39.210Z,1429290339.210 [NAL9602](INFO): SBD MO Status=0, MOMSN=52511, MT Status=0, MTMSN=0
2015-04-17T17:05:39.210Z,1429290339.210 [NAL9602](INFO): No messages in MT queue
2015-04-17T17:05:40.498Z,1429290340.498 [NAL9602](INFO): GPS fix at 20150417T170328: (36.802585, -121.787069)
2015-04-17T17:05:40.530Z,1429290340.530 [Default:CheckIn:Read_GPS] Stopped
2015-04-17T17:05:40.530Z,1429290340.530 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-04-17T17:05:42.542Z,1429290342.542 [CBIT](DEBUG): EFC running - data check-sum false
2015-04-17T17:05:43.860Z,1429290343.860 [Reporter](INFO): NAL9602.sigQuality 4 count
2015-04-17T17:05:57.019Z,1429290357.019 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-04-17T17:05:57.019Z,1429290357.019 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T17:05:57.019Z,1429290357.019 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-04-17T17:05:57.020Z,1429290357.020 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-04-17T17:05:57.020Z,1429290357.020 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-04-17T17:05:57.495Z,1429290357.495 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 67.686023 min 
2015-04-17T17:05:57.495Z,1429290357.495 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-04-17T17:05:57.495Z,1429290357.495 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T17:05:57.496Z,1429290357.496 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-04-17T17:05:57.496Z,1429290357.496 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-04-17T17:06:03.940Z,1429290363.940 [NAL9602](INFO): SBD MO Status=2, MOMSN=52512, MT Status=2, MTMSN=0
2015-04-17T17:06:03.940Z,1429290363.940 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T17:06:08.212Z,1429290368.212 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T17:06:20.128Z,1429290380.128 [Reporter](INFO): NAL9602.sigQuality 2 count
2015-04-17T17:06:33.392Z,1429290393.392 [NAL9602](INFO): SBD MO Status=2, MOMSN=52512, MT Status=2, MTMSN=0
2015-04-17T17:06:33.392Z,1429290393.392 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T17:06:37.814Z,1429290397.814 [Reporter](INFO): NAL9602.sigQuality 0 count
2015-04-17T17:06:46.387Z,1429290406.387 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T17:06:46.387Z,1429290406.387 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T17:06:56.621Z,1429290416.621 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:06:56.622Z,1429290416.622 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T17:06:56.622Z,1429290416.622 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:06:57.063Z,1429290417.063 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:06:57.191Z,1429290417.191 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:06:58.563Z,1429290418.563 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T17:06:58.563Z,1429290418.563 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T17:07:02.921Z,1429290422.921 [BPC1](ERROR): Failed to receive battery data
2015-04-17T17:07:02.921Z,1429290422.921 [BPC1] Communications Fault, FailCount= 1
2015-04-17T17:07:02.921Z,1429290422.921 [BPC1](ERROR): Communications Fault
2015-04-17T17:07:02.979Z,1429290422.979 [CBIT](ERROR): Communications Fault in component: BPC1
2015-04-17T17:07:04.261Z,1429290424.261 [CBIT](INFO): Clearing failed state for component BPC1
2015-04-17T17:07:04.261Z,1429290424.261 [BPC1] No Fault, FailCount= 1
2015-04-17T17:07:08.794Z,1429290428.794 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:07:08.794Z,1429290428.794 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T17:07:08.794Z,1429290428.794 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:07:09.293Z,1429290429.293 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:07:09.416Z,1429290429.416 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:07:10.701Z,1429290430.701 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T17:07:10.701Z,1429290430.701 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T17:07:20.975Z,1429290440.975 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:07:20.975Z,1429290440.975 [Aanderaa_O2] Communications Fault, FailCount= 3
2015-04-17T17:07:20.975Z,1429290440.975 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:07:21.417Z,1429290441.417 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:07:21.417Z,1429290441.417 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2015-04-17T17:07:21.537Z,1429290441.537 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:09:26.721Z,1429290566.721 [Reporter](INFO): NAL9602.sigQuality 1 count
2015-04-17T17:09:51.278Z,1429290591.278 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2015-04-17T17:09:51.278Z,1429290591.278 [Aanderaa_O2] No Fault, FailCount= 3
2015-04-17T17:09:52.603Z,1429290592.603 [BPC1](DEBUG): Received data from all battery sticks
2015-04-17T17:10:01.410Z,1429290601.410 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:10:01.410Z,1429290601.410 [Aanderaa_O2] Communications Fault, FailCount= 1
2015-04-17T17:10:01.410Z,1429290601.410 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:10:01.849Z,1429290601.849 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:10:01.974Z,1429290601.974 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:10:03.198Z,1429290603.198 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T17:10:03.198Z,1429290603.198 [Aanderaa_O2] No Fault, FailCount= 1
2015-04-17T17:10:03.583Z,1429290603.583 [NAL9602](INFO): SBD MO Status=2, MOMSN=52512, MT Status=2, MTMSN=0
2015-04-17T17:10:03.583Z,1429290603.583 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-04-17T17:10:07.667Z,1429290607.667 [Reporter](INFO): NAL9602.sigQuality 5 count
2015-04-17T17:10:13.263Z,1429290613.263 [Aanderaa_O2](FAULT): Timed out starting
2015-04-17T17:10:13.263Z,1429290613.263 [Aanderaa_O2] Communications Fault, FailCount= 2
2015-04-17T17:10:13.263Z,1429290613.263 [Aanderaa_O2](ERROR): Communications Fault
2015-04-17T17:10:13.671Z,1429290613.671 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2015-04-17T17:10:13.812Z,1429290613.812 [Aanderaa_O2](INFO): Powering down
2015-04-17T17:10:15.011Z,1429290615.011 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2015-04-17T17:10:15.011Z,1429290615.011 [Aanderaa_O2] No Fault, FailCount= 2
2015-04-17T17:10:24.007Z,1429290624.007 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=52512, MT Status=1, MTMSN=76
2015-04-17T17:10:24.068Z,1429290624.068 [NAL9602](INFO): Sent 131 bytes from file Logs/20150417T155520/Courier0032.lzma
2015-04-17T17:10:24.068Z,1429290624.068 [NAL9602](INFO): Packets left to send: 0
2015-04-17T17:10:24.070Z,1429290624.070 [NAL9602](INFO): Stored copy of sent data in Logs/20150417T155520/Courier0032.lzma.parts/0000.sbd
2015-04-17T17:10:24.070Z,1429290624.070 [NAL9602](INFO): Completed sending Logs/20150417T155520/Courier0032.lzma
2015-04-17T17:10:24.581Z,1429290624.581 [NAL9602](INFO): Received command:restart logs