2017-08-28T18:00:03.109Z,1503943203.109 [Supervisor](DEBUG): Initializing supervisor.
2017-08-28T18:00:03.112Z,1503943203.112 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-08-28T18:00:03.113Z,1503943203.113 [SyncHandler](INFO): Protected caller Thread ID is 1000
2017-08-28T18:00:03.113Z,1503943203.113 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-08-28T18:00:03.114Z,1503943203.114 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-08-28T18:00:03.115Z,1503943203.115 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1001
2017-08-28T18:00:03.117Z,1503943203.117 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-08-28T18:00:03.130Z,1503943203.130 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-08-28T18:00:03.131Z,1503943203.131 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-08-28T18:00:03.131Z,1503943203.131 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1002
2017-08-28T18:00:03.132Z,1503943203.132 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-08-28T18:00:03.133Z,1503943203.133 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-08-28T18:00:03.133Z,1503943203.133 [logger ThreadHandler](INFO): Protected caller Thread ID is 1003
2017-08-28T18:00:03.135Z,1503943203.135 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-08-28T18:00:03.136Z,1503943203.136 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-08-28T18:00:03.137Z,1503943203.137 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-08-28T18:00:03.332Z,1503943203.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-08-28T18:00:03.333Z,1503943203.333 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-08-28T18:00:03.468Z,1503943203.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-08-28T18:00:03.469Z,1503943203.469 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-08-28T18:00:03.822Z,1503943203.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-08-28T18:00:03.823Z,1503943203.823 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-08-28T18:00:03.965Z,1503943203.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-08-28T18:00:03.966Z,1503943203.966 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-08-28T18:00:04.052Z,1503943204.052 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-08-28T18:00:04.265Z,1503943204.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-08-28T18:00:04.266Z,1503943204.266 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-08-28T18:00:04.388Z,1503943204.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-08-28T18:00:04.389Z,1503943204.389 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-08-28T18:00:04.686Z,1503943204.686 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-08-28T18:00:04.687Z,1503943204.687 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-08-28T18:00:05.054Z,1503943205.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-08-28T18:00:05.054Z,1503943205.054 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-08-28T18:00:05.368Z,1503943205.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-08-28T18:00:05.368Z,1503943205.368 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-08-28T18:00:05.886Z,1503943205.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-08-28T18:00:05.887Z,1503943205.887 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-08-28T18:00:06.095Z,1503943206.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-08-28T18:00:06.096Z,1503943206.096 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-08-28T18:00:06.200Z,1503943206.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-08-28T18:00:06.201Z,1503943206.201 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-08-28T18:00:06.673Z,1503943206.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-08-28T18:00:06.673Z,1503943206.673 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-08-28T18:00:06.787Z,1503943206.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-08-28T18:00:06.789Z,1503943206.789 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-08-28T18:00:06.790Z,1503943206.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-08-28T18:00:07.033Z,1503943207.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-08-28T18:00:07.034Z,1503943207.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-08-28T18:00:07.140Z,1503943207.140 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-08-28T18:00:07.236Z,1503943207.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-08-28T18:00:07.343Z,1503943207.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-08-28T18:00:07.434Z,1503943207.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-08-28T18:00:07.568Z,1503943207.568 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-08-28T18:00:07.730Z,1503943207.730 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-08-28T18:00:07.830Z,1503943207.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-08-28T18:00:07.927Z,1503943207.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-08-28T18:00:08.016Z,1503943208.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-08-28T18:00:08.202Z,1503943208.202 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-08-28T18:00:08.283Z,1503943208.283 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-08-28T18:00:08.295Z,1503943208.295 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-08-28T18:00:08.423Z,1503943208.423 [SBIT](DEBUG): Construct Startup Built In Test.
2017-08-28T18:00:08.454Z,1503943208.454 [SBIT] Loaded
2017-08-28T18:00:08.454Z,1503943208.454 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-08-28T18:00:08.455Z,1503943208.455 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-08-28T18:00:08.483Z,1503943208.483 [IBIT] Loaded
2017-08-28T18:00:08.483Z,1503943208.483 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-08-28T18:00:08.487Z,1503943208.487 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-08-28T18:00:08.637Z,1503943208.637 [CBIT] Loaded
2017-08-28T18:00:08.637Z,1503943208.637 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-08-28T18:00:08.638Z,1503943208.638 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-08-28T18:00:08.638Z,1503943208.638 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-08-28T18:00:08.706Z,1503943208.706 [VerticalControl](DEBUG): Construct VerticalControl.
2017-08-28T18:00:08.806Z,1503943208.806 [VerticalControl] Loaded
2017-08-28T18:00:08.807Z,1503943208.807 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-08-28T18:00:08.807Z,1503943208.807 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-08-28T18:00:08.870Z,1503943208.870 [HorizontalControl] Loaded
2017-08-28T18:00:08.870Z,1503943208.870 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-08-28T18:00:08.871Z,1503943208.871 [SpeedControl](DEBUG): Construct SpeedControl.
2017-08-28T18:00:08.877Z,1503943208.877 [SpeedControl] Loaded
2017-08-28T18:00:08.877Z,1503943208.877 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-08-28T18:00:08.878Z,1503943208.878 [BackSeatDriver](DEBUG): Construct BackSeatDriver.
2017-08-28T18:00:08.880Z,1503943208.880 [BackSeatDriver] Loaded
2017-08-28T18:00:08.881Z,1503943208.881 [ComponentRegistry](DEBUG): SyncComponent "BackSeatDriver" handled in the control thread.
2017-08-28T18:00:08.881Z,1503943208.881 [LoopControl](DEBUG): Construct LoopControl.
2017-08-28T18:00:08.882Z,1503943208.882 [LoopControl] Loaded
2017-08-28T18:00:08.882Z,1503943208.882 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-08-28T18:00:08.883Z,1503943208.883 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-08-28T18:00:08.883Z,1503943208.883 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-08-28T18:00:08.907Z,1503943208.907 [DepthRateCalculator] Loaded
2017-08-28T18:00:08.908Z,1503943208.908 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-08-28T18:00:08.913Z,1503943208.913 [PitchRateCalculator] Loaded
2017-08-28T18:00:08.913Z,1503943208.913 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-08-28T18:00:08.929Z,1503943208.929 [SpeedCalculator] Loaded
2017-08-28T18:00:08.929Z,1503943208.929 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-08-28T18:00:08.951Z,1503943208.951 [TempGradientCalculator] Loaded
2017-08-28T18:00:08.951Z,1503943208.951 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-08-28T18:00:08.967Z,1503943208.967 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-08-28T18:00:08.967Z,1503943208.967 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-08-28T18:00:08.972Z,1503943208.972 [YawRateCalculator] Loaded
2017-08-28T18:00:08.973Z,1503943208.973 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-08-28T18:00:08.973Z,1503943208.973 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-08-28T18:00:08.974Z,1503943208.974 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-08-28T18:00:09.053Z,1503943209.053 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-08-28T18:00:09.053Z,1503943209.053 [StratificationFrontDetector](DEBUG): (re)initializing
2017-08-28T18:00:09.053Z,1503943209.053 [StratificationFrontDetector] Loaded
2017-08-28T18:00:09.053Z,1503943209.053 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-08-28T18:00:09.054Z,1503943209.054 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-08-28T18:00:09.054Z,1503943209.054 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-08-28T18:00:09.286Z,1503943209.286 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-08-28T18:00:09.287Z,1503943209.287 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-08-28T18:00:09.390Z,1503943209.390 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-08-28T18:00:09.390Z,1503943209.390 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-08-28T18:00:09.444Z,1503943209.444 [DeadReckonUsingSpeedCalculator] Loaded
2017-08-28T18:00:09.444Z,1503943209.444 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-08-28T18:00:09.462Z,1503943209.462 [NavChart] Loaded
2017-08-28T18:00:09.462Z,1503943209.462 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-08-28T18:00:09.466Z,1503943209.466 [UniversalFixResidualReporter] Loaded
2017-08-28T18:00:09.467Z,1503943209.467 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-08-28T18:00:09.467Z,1503943209.467 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-08-28T18:00:09.468Z,1503943209.468 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-08-28T18:00:09.474Z,1503943209.474 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-08-28T18:00:09.475Z,1503943209.475 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-08-28T18:00:09.614Z,1503943209.614 [Aanderaa_O2] Loaded
2017-08-28T18:00:09.614Z,1503943209.614 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-08-28T18:00:09.626Z,1503943209.626 [CANONSampler] Loaded
2017-08-28T18:00:09.626Z,1503943209.626 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2017-08-28T18:00:09.706Z,1503943209.706 [CTD_NeilBrown] Loaded
2017-08-28T18:00:09.706Z,1503943209.706 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-08-28T18:00:09.707Z,1503943209.707 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2017-08-28T18:00:09.708Z,1503943209.708 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1085
2017-08-28T18:00:09.742Z,1503943209.742 [CTD_Seabird] Loaded
2017-08-28T18:00:09.743Z,1503943209.743 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-08-28T18:00:09.744Z,1503943209.744 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2017-08-28T18:00:09.744Z,1503943209.744 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1086
2017-08-28T18:00:09.759Z,1503943209.759 [PAR_Licor] Loaded
2017-08-28T18:00:09.760Z,1503943209.760 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-08-28T18:00:09.811Z,1503943209.811 [WetLabsBB2FL] Loaded
2017-08-28T18:00:09.811Z,1503943209.811 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-08-28T18:00:09.812Z,1503943209.812 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0
2017-08-28T18:00:09.813Z,1503943209.813 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1087
2017-08-28T18:00:09.813Z,1503943209.813 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-08-28T18:00:09.814Z,1503943209.814 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-08-28T18:00:10.112Z,1503943210.112 [DataOverHttps] Loaded
2017-08-28T18:00:10.112Z,1503943210.112 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-08-28T18:00:10.126Z,1503943210.126 [Depth_Keller] Loaded
2017-08-28T18:00:10.126Z,1503943210.126 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-08-28T18:00:10.131Z,1503943210.131 [DropWeight] Loaded
2017-08-28T18:00:10.132Z,1503943210.132 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-08-28T18:00:10.223Z,1503943210.223 [NAL9602] Loaded
2017-08-28T18:00:10.223Z,1503943210.223 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-08-28T18:00:10.232Z,1503943210.232 [Onboard] Loaded
2017-08-28T18:00:10.232Z,1503943210.232 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-08-28T18:00:10.240Z,1503943210.240 [Radio_Surface] Loaded
2017-08-28T18:00:10.240Z,1503943210.240 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-08-28T18:00:10.241Z,1503943210.241 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A254E0
2017-08-28T18:00:10.241Z,1503943210.241 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1088
2017-08-28T18:00:10.289Z,1503943210.289 [PNI_TCM] Loaded
2017-08-28T18:00:10.290Z,1503943210.290 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-08-28T18:00:12.146Z,1503943212.146 [BPC1] Loaded
2017-08-28T18:00:12.147Z,1503943212.147 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-08-28T18:00:12.147Z,1503943212.147 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-08-28T18:00:12.148Z,1503943212.148 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-08-28T18:00:12.243Z,1503943212.243 [BuoyancyServo] Loaded
2017-08-28T18:00:12.244Z,1503943212.244 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-08-28T18:00:12.256Z,1503943212.256 [ElevatorServo] Loaded
2017-08-28T18:00:12.256Z,1503943212.256 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-08-28T18:00:12.268Z,1503943212.268 [MassServo] Loaded
2017-08-28T18:00:12.268Z,1503943212.268 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-08-28T18:00:12.280Z,1503943212.280 [RudderServo] Loaded
2017-08-28T18:00:12.281Z,1503943212.281 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-08-28T18:00:12.292Z,1503943212.292 [ThrusterServo] Loaded
2017-08-28T18:00:12.293Z,1503943212.293 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-08-28T18:00:12.293Z,1503943212.293 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-08-28T18:00:12.294Z,1503943212.294 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-08-28T18:00:12.391Z,1503943212.391 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-08-28T18:00:12.392Z,1503943212.392 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-08-28T18:00:12.416Z,1503943212.416 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-08-28T18:00:12.420Z,1503943212.420 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-08-28T18:00:12.421Z,1503943212.421 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-08-28T18:00:12.428Z,1503943212.428 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-08-28T18:00:12.429Z,1503943212.429 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD64E0
2017-08-28T18:00:12.429Z,1503943212.429 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1089
2017-08-28T18:00:12.434Z,1503943212.434 [Supervisor](INFO): Main Thread ID is 764
2017-08-28T18:00:12.434Z,1503943212.434 [Supervisor](DEBUG): Running supervisor.
2017-08-28T18:00:12.435Z,1503943212.435 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1090
2017-08-28T18:00:12.437Z,1503943212.437 [controlThread ThreadHandler](INFO): Handler Thread ID is 1091
2017-08-28T18:00:12.438Z,1503943212.438 [controlThread](DEBUG): Initializing ControlThread
2017-08-28T18:00:12.439Z,1503943212.439 [SBIT](INFO): Initialize SBIT Component.
2017-08-28T18:00:12.439Z,1503943212.439 [SBIT](IMPORTANT): git: 2017-08-02
2017-08-28T18:00:12.440Z,1503943212.440 [SBIT](INFO): git hash: b8bd19726fcc84d3133bb9e1cbad184cc93bb52c
2017-08-28T18:00:12.441Z,1503943212.441 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-08-28T18:00:12.442Z,1503943212.442 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-08-28T18:00:12.443Z,1503943212.443 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2017-08-28T18:00:12.443Z,1503943212.443 [IBIT](INFO): Initialize IBIT Component.
2017-08-28T18:00:12.444Z,1503943212.444 [CBIT](DEBUG): Initialize CBIT Component.
2017-08-28T18:00:12.444Z,1503943212.444 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-08-28T18:00:12.445Z,1503943212.445 [logger ThreadHandler](INFO): Handler Thread ID is 1092
2017-08-28T18:00:12.471Z,1503943212.471 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1093
2017-08-28T18:00:12.472Z,1503943212.472 [CTD_NeilBrown](INFO): Powering down
2017-08-28T18:00:12.501Z,1503943212.501 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1094
2017-08-28T18:00:12.501Z,1503943212.501 [CTD_Seabird](INFO): Initializing
2017-08-28T18:00:12.528Z,1503943212.528 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-08-28T18:00:12.530Z,1503943212.530 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-08-28T18:00:12.531Z,1503943212.531 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-08-28T18:00:12.532Z,1503943212.532 [BackSeatDriver](DEBUG): Initialize BackSeatDriver Component.
2017-08-28T18:00:12.548Z,1503943212.548 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1096
2017-08-28T18:00:12.550Z,1503943212.550 [WetLabsBB2FL](INFO): Powering down
2017-08-28T18:00:12.600Z,1503943212.600 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1097
2017-08-28T18:00:12.606Z,1503943212.606 [Radio_Surface](INFO): Powering up
2017-08-28T18:00:12.620Z,1503943212.620 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1099
2017-08-28T18:00:12.623Z,1503943212.623 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-08-28T18:00:12.624Z,1503943212.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-08-28T18:00:12.624Z,1503943212.624 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-08-28T18:00:12.624Z,1503943212.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-08-28T18:00:12.624Z,1503943212.624 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-08-28T18:00:12.624Z,1503943212.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-08-28T18:00:12.625Z,1503943212.625 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-08-28T18:00:12.625Z,1503943212.625 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-08-28T18:00:12.625Z,1503943212.625 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-08-28T18:00:12.625Z,1503943212.625 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-08-28T18:00:12.625Z,1503943212.625 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-08-28T18:00:12.625Z,1503943212.625 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-08-28T18:00:12.626Z,1503943212.626 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-08-28T18:00:12.626Z,1503943212.626 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-08-28T18:00:12.626Z,1503943212.626 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-08-28T18:00:12.626Z,1503943212.626 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-08-28T18:00:12.654Z,1503943212.654 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-08-28T18:00:12.654Z,1503943212.654 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-08-28T18:00:12.655Z,1503943212.655 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-08-28T18:00:12.668Z,1503943212.668 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-08-28T18:00:12.668Z,1503943212.668 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-08-28T18:00:12.669Z,1503943212.669 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-08-28T18:00:12.670Z,1503943212.670 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-08-28T18:00:12.671Z,1503943212.671 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T18:00:12.672Z,1503943212.672 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T18:00:12.672Z,1503943212.672 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T18:00:12.696Z,1503943212.696 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T18:00:12.696Z,1503943212.696 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T18:00:12.697Z,1503943212.697 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T18:00:12.697Z,1503943212.697 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-08-28T18:00:12.697Z,1503943212.697 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-08-28T18:00:12.745Z,1503943212.745 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-08-28T18:00:12.779Z,1503943212.779 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-08-28T18:00:12.826Z,1503943212.826 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2017-08-28T18:00:12.827Z,1503943212.827 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-08-28T18:00:12.845Z,1503943212.845 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-08-28T18:00:12.845Z,1503943212.845 [CTD_Seabird](INFO): Powering down
2017-08-28T18:00:12.929Z,1503943212.929 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-08-28T18:00:12.930Z,1503943212.930 [Default:A.Wait](DEBUG): Construct Wait.
2017-08-28T18:00:12.932Z,1503943212.932 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-08-28T18:00:12.954Z,1503943212.954 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-08-28T18:00:12.978Z,1503943212.978 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-08-28T18:00:13.009Z,1503943213.009 [Default:E.Execute](DEBUG): Construct Execute.
2017-08-28T18:00:13.012Z,1503943213.012 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Guidance="Tethys/Guidance"
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2017-08-28T18:00:13.033Z,1503943213.033 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,BackSeatDriver,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-08-28T18:00:13.092Z,1503943213.092 [CANONSampler](INFO): Powering down
2017-08-28T18:00:13.281Z,1503943213.281 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-08-28T18:00:13.313Z,1503943213.313 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-08-28T18:00:13.529Z,1503943213.529 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-08-28T18:00:13.621Z,1503943213.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:13.641Z,1503943213.641 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:13.836Z,1503943213.836 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-08-28T18:00:13.841Z,1503943213.841 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-08-28T18:00:13.848Z,1503943213.848 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-08-28T18:00:13.853Z,1503943213.853 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-08-28T18:00:13.867Z,1503943213.867 [MassServo](DEBUG): Initializing EZServoServo.
2017-08-28T18:00:13.873Z,1503943213.873 [MassServo](DEBUG): Initializing MassServo.
2017-08-28T18:00:13.887Z,1503943213.887 [RudderServo](DEBUG): Initializing EZServoServo.
2017-08-28T18:00:13.894Z,1503943213.894 [RudderServo](DEBUG): Initializing RudderServo.
2017-08-28T18:00:13.919Z,1503943213.919 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-08-28T18:00:13.925Z,1503943213.925 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-08-28T18:00:14.295Z,1503943214.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:14.296Z,1503943214.296 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:14.743Z,1503943214.743 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-08-28T18:00:14.743Z,1503943214.743 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-08-28T18:00:14.743Z,1503943214.743 [BuoyancyServo] Communications Fault, FailCount= 1
2017-08-28T18:00:14.743Z,1503943214.743 [BuoyancyServo](ERROR): Communications Fault
2017-08-28T18:00:14.951Z,1503943214.951 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-08-28T18:00:15.000Z,1503943215.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:15.002Z,1503943215.002 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:15.124Z,1503943215.124 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-08-28T18:00:15.124Z,1503943215.124 [BuoyancyServo](INFO): Powering down
2017-08-28T18:00:15.369Z,1503943215.369 [NAL9602](INFO): Powering up NAL9602
2017-08-28T18:00:15.412Z,1503943215.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:15.413Z,1503943215.413 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:15.828Z,1503943215.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:15.829Z,1503943215.829 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:16.277Z,1503943216.277 [Aanderaa_O2](INFO): Powering down
2017-08-28T18:00:16.409Z,1503943216.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:16.410Z,1503943216.410 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:16.677Z,1503943216.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:16.678Z,1503943216.678 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T18:00:17.291Z,1503943217.291 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-08-28T18:00:17.291Z,1503943217.291 [BuoyancyServo] No Fault, FailCount= 1
2017-08-28T18:00:17.628Z,1503943217.628 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-08-28T18:00:17.748Z,1503943217.748 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-08-28T18:00:26.375Z,1503943226.375 [NAL9602](INFO): NAL9602 initialized
2017-08-28T18:00:45.979Z,1503943245.979 [NAL9602](INFO): SBD MO Status=2, MOMSN=67, MT Status=2, MTMSN=0
2017-08-28T18:00:45.979Z,1503943245.979 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:00:58.378Z,1503943258.378 [NAL9602](INFO): SBD MO Status=0, MOMSN=67, MT Status=0, MTMSN=0
2017-08-28T18:00:58.378Z,1503943258.378 [NAL9602](INFO): No messages in MT queue
2017-08-28T18:00:59.580Z,1503943259.580 [NAL9602](INFO): GPS fix at 20170828T180057: (36.802336, -121.787124)
2017-08-28T18:01:06.373Z,1503943266.373 [NAL9602](INFO): Powering down
2017-08-28T18:01:24.128Z,1503943284.128 [SBIT](IMPORTANT): Beginning Startup BIT
2017-08-28T18:01:24.132Z,1503943284.132 [CBIT](IMPORTANT): Beginning ground fault scan
2017-08-28T18:01:34.787Z,1503943294.787 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.004872
CHAN A1 (24V): 4.792929
CHAN A2 (12V): -0.067397
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001641
CHAN B1 (3.15aV): -0.000414
CHAN B2 (3.15bV): 4.767929
CHAN B3 (GND): 0.027597
OPEN: -0.000482
Full Scale Calc: 4.765 mA, -1.589 mA
2017-08-28T18:01:56.539Z,1503943316.539 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:01:56.539Z,1503943316.539 [BPC1] Communications Fault, FailCount= 1
2017-08-28T18:01:56.539Z,1503943316.539 [BPC1](ERROR): Communications Fault
2017-08-28T18:01:56.662Z,1503943316.662 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:01:57.860Z,1503943317.860 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:01:57.860Z,1503943317.860 [BPC1] No Fault, FailCount= 1
2017-08-28T18:02:13.646Z,1503943333.646 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:02:13.646Z,1503943333.646 [BPC1] Communications Fault, FailCount= 2
2017-08-28T18:02:13.647Z,1503943333.647 [BPC1](ERROR): Communications Fault
2017-08-28T18:02:13.808Z,1503943333.808 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:02:15.032Z,1503943335.032 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:02:15.032Z,1503943335.032 [BPC1] No Fault, FailCount= 2
2017-08-28T18:02:17.760Z,1503943337.760 [SBIT](IMPORTANT): SBIT PASSED
2017-08-28T18:02:18.054Z,1503943338.054 [MissionManager](IMPORTANT): Started mission Startup
2017-08-28T18:02:18.054Z,1503943338.054 [Startup] Running Loop=1
2017-08-28T18:02:18.055Z,1503943338.055 [Startup](DEBUG): Aggregate::initialize Startup
2017-08-28T18:02:18.055Z,1503943338.055 [Startup:A.GoToSurface] Running Loop=1
2017-08-28T18:02:18.055Z,1503943338.055 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-08-28T18:02:18.055Z,1503943338.055 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-08-28T18:02:18.056Z,1503943338.056 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-08-28T18:02:18.056Z,1503943338.056 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-08-28T18:02:18.057Z,1503943338.057 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-08-28T18:02:18.057Z,1503943338.057 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-08-28T18:02:18.066Z,1503943338.066 [Startup:StartupSatComms] Running Loop=1
2017-08-28T18:02:18.066Z,1503943338.066 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-08-28T18:02:18.066Z,1503943338.066 [Startup:StartupSatComms:A] Running Loop=1
2017-08-28T18:02:18.453Z,1503943338.453 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-08-28T18:02:18.802Z,1503943338.802 [NAL9602](INFO): Powering up
2017-08-28T18:02:29.607Z,1503943349.607 [NAL9602](INFO): NAL9602 initialized
2017-08-28T18:02:31.254Z,1503943351.254 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:02:31.254Z,1503943351.254 [BPC1] Communications Fault, FailCount= 3
2017-08-28T18:02:31.254Z,1503943351.254 [BPC1](ERROR): Communications Fault
2017-08-28T18:02:31.377Z,1503943351.377 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:02:32.578Z,1503943352.578 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:02:32.578Z,1503943352.578 [BPC1] No Fault, FailCount= 3
2017-08-28T18:02:48.008Z,1503943368.008 [NAL9602](INFO): SBD MO Status=2, MOMSN=68, MT Status=2, MTMSN=0
2017-08-28T18:02:48.008Z,1503943368.008 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:02:51.645Z,1503943371.645 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:02:51.645Z,1503943371.645 [BPC1] Communications Fault, FailCount= 4
2017-08-28T18:02:51.645Z,1503943371.645 [BPC1](ERROR): Communications Fault
2017-08-28T18:02:51.765Z,1503943371.765 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:02:52.974Z,1503943372.974 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:02:52.975Z,1503943372.975 [BPC1] No Fault, FailCount= 4
2017-08-28T18:03:08.843Z,1503943388.843 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:03:08.843Z,1503943388.843 [BPC1] Communications Fault, FailCount= 5
2017-08-28T18:03:08.843Z,1503943388.843 [BPC1](ERROR): Communications Fault
2017-08-28T18:03:08.988Z,1503943388.988 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:03:10.170Z,1503943390.170 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:03:10.170Z,1503943390.170 [BPC1] No Fault, FailCount= 5
2017-08-28T18:03:13.611Z,1503943393.611 [NAL9602](INFO): SBD MO Status=2, MOMSN=68, MT Status=2, MTMSN=0
2017-08-28T18:03:13.611Z,1503943393.611 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:03:18.071Z,1503943398.071 [Startup:StartupSatComms:A](INFO): Timed out from 2017-08-28T18:02:18.1Z
2017-08-28T18:03:18.071Z,1503943398.071 [Startup:StartupSatComms:A] Stopped
2017-08-28T18:03:18.071Z,1503943398.071 [Startup:StartupSatComms:B] Running Loop=1
2017-08-28T18:03:18.472Z,1503943398.472 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-08-28T18:03:26.451Z,1503943406.451 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:03:26.451Z,1503943406.451 [BPC1] Communications Fault, FailCount= 6
2017-08-28T18:03:26.451Z,1503943406.451 [BPC1](ERROR): Communications Fault
2017-08-28T18:03:26.612Z,1503943406.612 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:03:27.622Z,1503943407.622 [NAL9602](INFO): SBD MO Status=2, MOMSN=68, MT Status=2, MTMSN=0
2017-08-28T18:03:27.622Z,1503943407.622 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:03:27.814Z,1503943407.814 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:03:27.814Z,1503943407.814 [BPC1] No Fault, FailCount= 6
2017-08-28T18:03:42.416Z,1503943422.416 [NAL9602](INFO): SBD MO Status=2, MOMSN=68, MT Status=2, MTMSN=0
2017-08-28T18:03:42.417Z,1503943422.417 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:03:44.058Z,1503943424.058 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:03:44.058Z,1503943424.058 [BPC1] Communications Fault, FailCount= 7
2017-08-28T18:03:44.059Z,1503943424.059 [BPC1](ERROR): Communications Fault
2017-08-28T18:03:44.184Z,1503943424.184 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:03:45.381Z,1503943425.381 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:03:45.382Z,1503943425.382 [BPC1] No Fault, FailCount= 7
2017-08-28T18:04:01.648Z,1503943441.648 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:04:01.648Z,1503943441.648 [BPC1] Communications Fault, FailCount= 8
2017-08-28T18:04:01.648Z,1503943441.648 [BPC1](ERROR): Communications Fault
2017-08-28T18:04:01.812Z,1503943441.812 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:04:02.989Z,1503943442.989 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:04:02.989Z,1503943442.989 [BPC1] No Fault, FailCount= 8
2017-08-28T18:04:09.625Z,1503943449.625 [NAL9602](INFO): SBD MO Status=0, MOMSN=68, MT Status=0, MTMSN=0
2017-08-28T18:04:09.625Z,1503943449.625 [NAL9602](INFO): No messages in MT queue
2017-08-28T18:04:18.264Z,1503943458.264 [Startup:StartupSatComms:B](INFO): Timed out from 2017-08-28T18:03:18.1Z
2017-08-28T18:04:18.264Z,1503943458.264 [Startup:StartupSatComms:B] Stopped
2017-08-28T18:04:18.264Z,1503943458.264 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-08-28T18:04:18.264Z,1503943458.264 [Startup:StartupSatComms] Stopped
2017-08-28T18:04:18.264Z,1503943458.264 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-08-28T18:04:18.265Z,1503943458.265 [Startup](INFO): Completed Startup
2017-08-28T18:04:18.265Z,1503943458.265 [MissionManager](INFO): Startup is completed.
2017-08-28T18:04:18.265Z,1503943458.265 [MissionManager](INFO): Uninitializing Mission Startup
2017-08-28T18:04:18.265Z,1503943458.265 [Startup] Stopped
2017-08-28T18:04:18.266Z,1503943458.266 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-08-28T18:04:18.266Z,1503943458.266 [Startup:A.GoToSurface] Stopped
2017-08-28T18:04:18.266Z,1503943458.266 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-08-28T18:04:18.682Z,1503943458.682 [MissionManager](IMPORTANT): Started mission Default
2017-08-28T18:04:18.682Z,1503943458.682 [Default] Running Loop=1
2017-08-28T18:04:18.682Z,1503943458.682 [Default](DEBUG): Aggregate::initialize Default
2017-08-28T18:04:18.682Z,1503943458.682 [Default:B.GoToSurface] Running Loop=1
2017-08-28T18:04:18.682Z,1503943458.682 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-08-28T18:04:18.683Z,1503943458.683 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-08-28T18:04:18.683Z,1503943458.683 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-08-28T18:04:18.683Z,1503943458.683 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-08-28T18:04:18.684Z,1503943458.684 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-08-28T18:04:18.684Z,1503943458.684 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-08-28T18:04:18.684Z,1503943458.684 [Default:A.Wait] Running Loop=1
2017-08-28T18:04:18.684Z,1503943458.684 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-08-28T18:04:31.823Z,1503943471.823 [NAL9602](INFO): SBD MO Status=1, MOMSN=69, MT Status=0, MTMSN=0
2017-08-28T18:04:31.873Z,1503943471.873 [NAL9602](INFO): Sent 25 bytes from file Logs/20170828T172032/Courier0020.lzma
2017-08-28T18:04:31.874Z,1503943471.874 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:04:31.875Z,1503943471.875 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T172032/Courier0020.lzma.parts/0000.sbd
2017-08-28T18:04:31.876Z,1503943471.876 [NAL9602](DEBUG): Completed sending Logs/20170828T172032/Courier0020.lzma
2017-08-28T18:04:31.944Z,1503943471.944 [Default:A.Wait](INFO): Done Waiting.
2017-08-28T18:04:31.945Z,1503943471.945 [Default:A.Wait] Stopped
2017-08-28T18:04:31.945Z,1503943471.945 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T18:04:32.280Z,1503943472.280 [Default:CheckIn] Running Loop=1
2017-08-28T18:04:32.280Z,1503943472.280 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T18:04:32.281Z,1503943472.281 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T18:04:32.652Z,1503943472.652 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-08-28T18:04:34.222Z,1503943474.222 [NAL9602](INFO): GPS fix at 20170828T180432: (36.802303, -121.787197)
2017-08-28T18:04:34.277Z,1503943474.277 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T18:04:34.289Z,1503943474.289 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-08-28T18:04:34.690Z,1503943474.690 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-08-28T18:04:47.256Z,1503943487.256 [NAL9602](INFO): SBD MO Status=1, MOMSN=70, MT Status=0, MTMSN=0
2017-08-28T18:04:47.310Z,1503943487.310 [NAL9602](INFO): Sent 216 bytes from file Logs/20170828T180003/Courier0000.lzma
2017-08-28T18:04:47.310Z,1503943487.310 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:04:47.311Z,1503943487.311 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Courier0000.lzma.parts/0000.sbd
2017-08-28T18:04:47.312Z,1503943487.312 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Courier0000.lzma
2017-08-28T18:04:50.390Z,1503943490.390 [BPC1](ERROR): Failed to parse bank A battery data
2017-08-28T18:04:50.391Z,1503943490.391 [BPC1] Data Fault, FailCount= 1
2017-08-28T18:04:50.391Z,1503943490.391 [BPC1](ERROR): Data Fault
2017-08-28T18:04:50.545Z,1503943490.545 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T18:04:51.706Z,1503943491.706 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:04:51.706Z,1503943491.706 [BPC1] No Fault, FailCount= 1
2017-08-28T18:04:55.153Z,1503943495.153 [NAL9602](INFO): SBD MO Status=1, MOMSN=71, MT Status=0, MTMSN=0
2017-08-28T18:04:55.206Z,1503943495.206 [NAL9602](INFO): Sent 63 bytes from file Logs/20170828T180003/Courier0004.lzma
2017-08-28T18:04:55.206Z,1503943495.206 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:04:55.208Z,1503943495.208 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Courier0004.lzma.parts/0000.sbd
2017-08-28T18:04:55.208Z,1503943495.208 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Courier0004.lzma
2017-08-28T18:05:05.345Z,1503943505.345 [NAL9602](INFO): SBD MO Status=1, MOMSN=72, MT Status=0, MTMSN=0
2017-08-28T18:05:05.398Z,1503943505.398 [NAL9602](INFO): Sent 165 bytes from file Logs/20170828T172032/Express0021.lzma
2017-08-28T18:05:05.399Z,1503943505.399 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:05:05.400Z,1503943505.400 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T172032/Express0021.lzma.parts/0000.sbd
2017-08-28T18:05:05.400Z,1503943505.400 [NAL9602](DEBUG): Completed sending Logs/20170828T172032/Express0021.lzma
2017-08-28T18:05:13.962Z,1503943513.962 [NAL9602](INFO): SBD MO Status=1, MOMSN=73, MT Status=0, MTMSN=0
2017-08-28T18:05:14.016Z,1503943514.016 [NAL9602](INFO): Sent 332 bytes from file Logs/20170828T180003/Express0001.lzma
2017-08-28T18:05:14.016Z,1503943514.016 [NAL9602](INFO): Packets left to send: 1
2017-08-28T18:05:14.018Z,1503943514.018 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0001.lzma.parts/0001.sbd
2017-08-28T18:05:22.992Z,1503943522.992 [NAL9602](INFO): SBD MO Status=1, MOMSN=74, MT Status=0, MTMSN=0
2017-08-28T18:05:23.046Z,1503943523.046 [NAL9602](INFO): Sent 296 bytes from file Logs/20170828T180003/Express0001.lzma
2017-08-28T18:05:23.046Z,1503943523.046 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:05:23.048Z,1503943523.048 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0001.lzma.parts/0000.sbd
2017-08-28T18:05:23.049Z,1503943523.049 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Express0001.lzma
2017-08-28T18:05:33.998Z,1503943533.998 [NAL9602](INFO): SBD MO Status=1, MOMSN=75, MT Status=0, MTMSN=0
2017-08-28T18:05:34.048Z,1503943534.048 [NAL9602](INFO): Sent 94 bytes from file Logs/20170828T180003/Express0005.lzma
2017-08-28T18:05:34.048Z,1503943534.048 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:05:34.052Z,1503943534.052 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0005.lzma.parts/0000.sbd
2017-08-28T18:05:34.053Z,1503943534.053 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Express0005.lzma
2017-08-28T18:06:03.202Z,1503943563.202 [NAL9602](INFO): SBD MO Status=0, MOMSN=76, MT Status=0, MTMSN=0
2017-08-28T18:06:03.361Z,1503943563.361 [Default:CheckIn:Read_Iridium] Stopped
2017-08-28T18:06:03.361Z,1503943563.361 [Default:CheckIn:C.Wait] Running Loop=1
2017-08-28T18:06:03.361Z,1503943563.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-08-28T18:06:08.806Z,1503943568.806 [NAL9602](INFO): Powering down
2017-08-28T18:10:12.558Z,1503943812.558 [CBIT](DEBUG): EFC running - data check-sum false
2017-08-28T18:11:03.680Z,1503943863.680 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-08-28T18:11:03.680Z,1503943863.680 [Default:CheckIn:C.Wait] Stopped
2017-08-28T18:11:03.685Z,1503943863.685 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T18:11:03.685Z,1503943863.685 [Default:CheckIn:D] Running Loop=1
2017-08-28T18:11:04.049Z,1503943864.049 [Default:CheckIn:D] Stopped
2017-08-28T18:11:04.049Z,1503943864.049 [Default:CheckIn:E] Running Loop=1
2017-08-28T18:11:04.448Z,1503943864.448 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.756079 min 
2017-08-28T18:11:04.450Z,1503943864.450 [Default:CheckIn:E] Stopped
2017-08-28T18:11:04.451Z,1503943864.451 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-08-28T18:11:04.451Z,1503943864.451 [Default:CheckIn] Stopped
2017-08-28T18:11:04.451Z,1503943864.451 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T18:11:04.451Z,1503943864.451 [Default:CheckIn](INFO): Running loop #2
2017-08-28T18:11:04.451Z,1503943864.451 [Default:CheckIn] Running Loop=2
2017-08-28T18:11:04.451Z,1503943864.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T18:11:04.451Z,1503943864.451 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T18:11:05.204Z,1503943865.204 [NAL9602](INFO): Powering up
2017-08-28T18:11:16.393Z,1503943876.393 [NAL9602](INFO): NAL9602 initialized
2017-08-28T18:11:25.648Z,1503943885.648 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:11:25.649Z,1503943885.649 [BPC1] Communications Fault, FailCount= 1
2017-08-28T18:11:25.649Z,1503943885.649 [BPC1](ERROR): Communications Fault
2017-08-28T18:11:25.797Z,1503943885.797 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:11:26.991Z,1503943886.991 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:11:26.991Z,1503943886.991 [BPC1] No Fault, FailCount= 1
2017-08-28T18:11:42.839Z,1503943902.839 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:11:42.839Z,1503943902.839 [BPC1] Communications Fault, FailCount= 2
2017-08-28T18:11:42.839Z,1503943902.839 [BPC1](ERROR): Communications Fault
2017-08-28T18:11:42.997Z,1503943902.997 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:11:44.162Z,1503943904.162 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:11:44.163Z,1503943904.163 [BPC1] No Fault, FailCount= 2
2017-08-28T18:12:00.422Z,1503943920.422 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:12:00.422Z,1503943920.422 [BPC1] Communications Fault, FailCount= 3
2017-08-28T18:12:00.422Z,1503943920.422 [BPC1](ERROR): Communications Fault
2017-08-28T18:12:00.544Z,1503943920.544 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:12:01.770Z,1503943921.770 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:12:01.770Z,1503943921.770 [BPC1] No Fault, FailCount= 3
2017-08-28T18:12:17.654Z,1503943937.654 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:12:17.654Z,1503943937.654 [BPC1] Communications Fault, FailCount= 4
2017-08-28T18:12:17.654Z,1503943937.654 [BPC1](ERROR): Communications Fault
2017-08-28T18:12:17.783Z,1503943937.783 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:12:18.965Z,1503943938.965 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:12:18.966Z,1503943938.966 [BPC1] No Fault, FailCount= 4
2017-08-28T18:12:38.827Z,1503943958.827 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:12:38.827Z,1503943958.827 [BPC1] Communications Fault, FailCount= 5
2017-08-28T18:12:38.827Z,1503943958.827 [BPC1](ERROR): Communications Fault
2017-08-28T18:12:38.989Z,1503943958.989 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:12:40.183Z,1503943960.183 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:12:40.183Z,1503943960.183 [BPC1] No Fault, FailCount= 5
2017-08-28T18:12:56.436Z,1503943976.436 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:12:56.436Z,1503943976.436 [BPC1] Communications Fault, FailCount= 6
2017-08-28T18:12:56.436Z,1503943976.436 [BPC1](ERROR): Communications Fault
2017-08-28T18:12:56.573Z,1503943976.573 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:12:57.775Z,1503943977.775 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:12:57.775Z,1503943977.775 [BPC1] No Fault, FailCount= 6
2017-08-28T18:13:14.036Z,1503943994.036 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:13:14.036Z,1503943994.036 [BPC1] Communications Fault, FailCount= 7
2017-08-28T18:13:14.036Z,1503943994.036 [BPC1](ERROR): Communications Fault
2017-08-28T18:13:14.185Z,1503943994.185 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:13:15.351Z,1503943995.351 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:13:15.351Z,1503943995.351 [BPC1] No Fault, FailCount= 7
2017-08-28T18:13:31.660Z,1503944011.660 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:13:31.660Z,1503944011.660 [BPC1] Communications Fault, FailCount= 8
2017-08-28T18:13:31.660Z,1503944011.660 [BPC1](ERROR): Communications Fault
2017-08-28T18:13:31.781Z,1503944011.781 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:13:32.994Z,1503944012.994 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:13:32.995Z,1503944012.995 [BPC1] No Fault, FailCount= 8
2017-08-28T18:13:49.239Z,1503944029.239 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:13:49.239Z,1503944029.239 [BPC1] Communications Fault, FailCount= 9
2017-08-28T18:13:49.239Z,1503944029.239 [BPC1](ERROR): Communications Fault
2017-08-28T18:13:49.371Z,1503944029.371 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:13:50.590Z,1503944030.590 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:13:50.591Z,1503944030.591 [BPC1] No Fault, FailCount= 9
2017-08-28T18:14:58.400Z,1503944098.400 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=77, MT Status=1, MTMSN=899
2017-08-28T18:14:58.401Z,1503944098.401 [NAL9602](INFO): Data available in MT queue
2017-08-28T18:14:58.897Z,1503944098.897 [NAL9602](INFO): Received command:ibit
2017-08-28T18:14:58.906Z,1503944098.906 [CommandLine](IMPORTANT): got command ibit
2017-08-28T18:14:59.145Z,1503944099.145 [IBIT](IMPORTANT): Beginning Initiated BIT
2017-08-28T18:14:59.145Z,1503944099.145 [IBIT](IMPORTANT): Beginning control surface checks.
2017-08-28T18:14:59.148Z,1503944099.148 [CBIT](IMPORTANT): Beginning ground fault scan
2017-08-28T18:15:00.019Z,1503944100.019 [NAL9602](INFO): GPS fix at 20170828T181458: (36.802310, -121.787209)
2017-08-28T18:15:02.403Z,1503944102.403 [NAL9602](INFO): GPS fix at 20170828T181501: (36.802308, -121.787211)
2017-08-28T18:15:05.302Z,1503944105.302 [NAL9602](INFO): GPS fix at 20170828T181504: (36.802306, -121.787212)
2017-08-28T18:15:08.123Z,1503944108.123 [NAL9602](INFO): GPS fix at 20170828T181506: (36.802305, -121.787212)
2017-08-28T18:15:09.467Z,1503944109.467 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.005389
CHAN A1 (24V): 0.125995
CHAN A2 (12V): -0.002383
CHAN A3 (5V): 0.000001
CHAN B0 (3.3V): -0.000059
CHAN B1 (3.15aV): -0.000158
CHAN B2 (3.15bV): 0.000067
CHAN B3 (GND): -0.000057
OPEN: -0.000388
Full Scale Calc: 4.765 mA, -1.589 mA
2017-08-28T18:15:11.305Z,1503944111.305 [NAL9602](INFO): GPS fix at 20170828T181510: (36.802304, -121.787212)
2017-08-28T18:15:14.107Z,1503944114.107 [NAL9602](INFO): GPS fix at 20170828T181512: (36.802303, -121.787212)
2017-08-28T18:15:17.321Z,1503944117.321 [NAL9602](INFO): GPS fix at 20170828T181516: (36.802302, -121.787211)
2017-08-28T18:15:20.101Z,1503944120.101 [NAL9602](INFO): GPS fix at 20170828T181518: (36.802302, -121.787211)
2017-08-28T18:15:23.357Z,1503944123.357 [NAL9602](INFO): GPS fix at 20170828T181522: (36.802301, -121.787211)
2017-08-28T18:15:26.155Z,1503944126.155 [NAL9602](INFO): GPS fix at 20170828T181525: (36.802300, -121.787211)
2017-08-28T18:15:29.348Z,1503944129.348 [NAL9602](INFO): GPS fix at 20170828T181528: (36.802300, -121.787211)
2017-08-28T18:15:32.162Z,1503944132.162 [NAL9602](INFO): GPS fix at 20170828T181531: (36.802300, -121.787211)
2017-08-28T18:15:35.387Z,1503944135.387 [NAL9602](INFO): GPS fix at 20170828T181534: (36.802299, -121.787210)
2017-08-28T18:15:38.186Z,1503944138.186 [NAL9602](INFO): GPS fix at 20170828T181537: (36.802299, -121.787210)
2017-08-28T18:15:41.395Z,1503944141.395 [NAL9602](INFO): GPS fix at 20170828T181540: (36.802298, -121.787210)
2017-08-28T18:15:44.200Z,1503944144.200 [NAL9602](INFO): GPS fix at 20170828T181543: (36.802297, -121.787211)
2017-08-28T18:15:45.170Z,1503944145.170 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802296 Longitude: -121.787209
2017-08-28T18:15:45.586Z,1503944145.586 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2017-08-28T18:15:45.962Z,1503944145.962 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2017-08-28T18:15:45.963Z,1503944145.963 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2017-08-28T18:15:45.963Z,1503944145.963 [IBIT](IMPORTANT): Pressure:10.034966 PSI
2017-08-28T18:15:45.964Z,1503944145.964 [IBIT](IMPORTANT): Humidity:8.912221 %
2017-08-28T18:15:46.343Z,1503944146.343 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2017-08-28T18:15:46.343Z,1503944146.343 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2017-08-28T18:15:46.344Z,1503944146.344 [IBIT](IMPORTANT): massDefault: -1.300000 cm
2017-08-28T18:15:46.344Z,1503944146.344 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2017-08-28T18:15:46.344Z,1503944146.344 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2017-08-28T18:15:46.344Z,1503944146.344 [IBIT](IMPORTANT): IBIT PASSED
2017-08-28T18:15:46.640Z,1503944146.640 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T18:15:46.640Z,1503944146.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-08-28T18:15:47.381Z,1503944147.381 [NAL9602](INFO): GPS fix at 20170828T181546: (36.802296, -121.787211)
2017-08-28T18:16:18.813Z,1503944178.813 [NAL9602](INFO): SBD MO Status=1, MOMSN=78, MT Status=0, MTMSN=0
2017-08-28T18:16:18.871Z,1503944178.871 [NAL9602](INFO): Sent 64 bytes from file Logs/20170828T180003/Courier0008.lzma
2017-08-28T18:16:18.871Z,1503944178.871 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:16:18.873Z,1503944178.873 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Courier0008.lzma.parts/0000.sbd
2017-08-28T18:16:18.873Z,1503944178.873 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Courier0008.lzma
2017-08-28T18:16:31.469Z,1503944191.469 [NAL9602](INFO): SBD MO Status=1, MOMSN=79, MT Status=0, MTMSN=0
2017-08-28T18:16:31.535Z,1503944191.535 [NAL9602](INFO): Sent 332 bytes from file Logs/20170828T180003/Express0009.lzma
2017-08-28T18:16:31.535Z,1503944191.535 [NAL9602](INFO): Packets left to send: 2
2017-08-28T18:16:31.537Z,1503944191.537 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0009.lzma.parts/0002.sbd
2017-08-28T18:16:48.501Z,1503944208.501 [NAL9602](INFO): SBD MO Status=1, MOMSN=80, MT Status=0, MTMSN=0
2017-08-28T18:16:48.550Z,1503944208.550 [NAL9602](INFO): Sent 332 bytes from file Logs/20170828T180003/Express0009.lzma
2017-08-28T18:16:48.550Z,1503944208.550 [NAL9602](INFO): Packets left to send: 1
2017-08-28T18:16:48.551Z,1503944208.551 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0009.lzma.parts/0001.sbd
2017-08-28T18:17:01.889Z,1503944221.889 [NAL9602](INFO): SBD MO Status=2, MOMSN=81, MT Status=2, MTMSN=0
2017-08-28T18:17:01.889Z,1503944221.889 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:17:20.103Z,1503944240.103 [NAL9602](INFO): SBD MO Status=2, MOMSN=81, MT Status=2, MTMSN=0
2017-08-28T18:17:20.103Z,1503944240.103 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:17:37.182Z,1503944257.182 [NAL9602](INFO): SBD MO Status=2, MOMSN=81, MT Status=2, MTMSN=0
2017-08-28T18:17:37.182Z,1503944257.182 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:18:02.606Z,1503944282.606 [NAL9602](INFO): SBD MO Status=1, MOMSN=81, MT Status=0, MTMSN=0
2017-08-28T18:18:02.655Z,1503944282.655 [NAL9602](INFO): Sent 104 bytes from file Logs/20170828T180003/Express0009.lzma
2017-08-28T18:18:02.656Z,1503944282.656 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:18:02.657Z,1503944282.657 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0009.lzma.parts/0000.sbd
2017-08-28T18:18:02.657Z,1503944282.657 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Express0009.lzma
2017-08-28T18:18:14.585Z,1503944294.585 [NAL9602](INFO): SBD MO Status=0, MOMSN=82, MT Status=0, MTMSN=0
2017-08-28T18:18:30.822Z,1503944310.822 [NAL9602](INFO): SBD MO Status=2, MOMSN=83, MT Status=2, MTMSN=0
2017-08-28T18:18:30.823Z,1503944310.823 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:18:43.415Z,1503944323.415 [NAL9602](INFO): SBD MO Status=1, MOMSN=83, MT Status=0, MTMSN=0
2017-08-28T18:18:43.468Z,1503944323.468 [NAL9602](INFO): Sent 64 bytes from file Logs/20170828T180003/Courier0012.lzma
2017-08-28T18:18:43.468Z,1503944323.468 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:18:43.470Z,1503944323.470 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Courier0012.lzma.parts/0000.sbd
2017-08-28T18:18:43.470Z,1503944323.470 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Courier0012.lzma
2017-08-28T18:18:55.245Z,1503944335.245 [NAL9602](INFO): SBD MO Status=1, MOMSN=84, MT Status=0, MTMSN=0
2017-08-28T18:18:55.299Z,1503944335.299 [NAL9602](INFO): Sent 62 bytes from file Logs/20170828T180003/Express0013.lzma
2017-08-28T18:18:55.299Z,1503944335.299 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:18:55.300Z,1503944335.300 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0013.lzma.parts/0000.sbd
2017-08-28T18:18:55.301Z,1503944335.301 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Express0013.lzma
2017-08-28T18:19:04.851Z,1503944344.851 [NAL9602](INFO): SBD MO Status=0, MOMSN=85, MT Status=0, MTMSN=0
2017-08-28T18:19:04.975Z,1503944344.975 [Default:CheckIn:Read_Iridium] Stopped
2017-08-28T18:19:04.975Z,1503944344.975 [Default:CheckIn:C.Wait] Running Loop=1
2017-08-28T18:19:04.975Z,1503944344.975 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-08-28T18:19:10.437Z,1503944350.437 [NAL9602](INFO): Powering down
2017-08-28T18:19:40.867Z,1503944380.867 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:19:40.867Z,1503944380.867 [BPC1] Communications Fault, FailCount= 1
2017-08-28T18:19:40.868Z,1503944380.868 [BPC1](ERROR): Communications Fault
2017-08-28T18:19:41.020Z,1503944381.020 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:19:42.218Z,1503944382.218 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:19:42.218Z,1503944382.218 [BPC1] No Fault, FailCount= 1
2017-08-28T18:20:12.623Z,1503944412.623 [CBIT](DEBUG): EFC running - data check-sum false
2017-08-28T18:21:18.473Z,1503944478.473 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:21:18.473Z,1503944478.473 [BPC1] Communications Fault, FailCount= 1
2017-08-28T18:21:18.473Z,1503944478.473 [BPC1](ERROR): Communications Fault
2017-08-28T18:21:18.627Z,1503944478.627 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:21:19.833Z,1503944479.833 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:21:19.833Z,1503944479.833 [BPC1] No Fault, FailCount= 1
2017-08-28T18:21:36.077Z,1503944496.077 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:21:36.077Z,1503944496.077 [BPC1] Communications Fault, FailCount= 2
2017-08-28T18:21:36.077Z,1503944496.077 [BPC1](ERROR): Communications Fault
2017-08-28T18:21:36.199Z,1503944496.199 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:21:37.401Z,1503944497.401 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:21:37.401Z,1503944497.401 [BPC1] No Fault, FailCount= 2
2017-08-28T18:21:53.674Z,1503944513.674 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:21:53.674Z,1503944513.674 [BPC1] Communications Fault, FailCount= 3
2017-08-28T18:21:53.674Z,1503944513.674 [BPC1](ERROR): Communications Fault
2017-08-28T18:21:53.798Z,1503944513.798 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:21:54.996Z,1503944514.996 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:21:54.996Z,1503944514.996 [BPC1] No Fault, FailCount= 3
2017-08-28T18:22:11.264Z,1503944531.264 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:22:11.264Z,1503944531.264 [BPC1] Communications Fault, FailCount= 4
2017-08-28T18:22:11.264Z,1503944531.264 [BPC1](ERROR): Communications Fault
2017-08-28T18:22:11.402Z,1503944531.402 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:22:12.604Z,1503944532.604 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:22:12.604Z,1503944532.604 [BPC1] No Fault, FailCount= 4
2017-08-28T18:22:28.880Z,1503944548.880 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:22:28.880Z,1503944548.880 [BPC1] Communications Fault, FailCount= 5
2017-08-28T18:22:28.880Z,1503944548.880 [BPC1](ERROR): Communications Fault
2017-08-28T18:22:29.002Z,1503944549.002 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:22:30.196Z,1503944550.196 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:22:30.196Z,1503944550.196 [BPC1] No Fault, FailCount= 5
2017-08-28T18:22:46.483Z,1503944566.483 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:22:46.483Z,1503944566.483 [BPC1] Communications Fault, FailCount= 6
2017-08-28T18:22:46.483Z,1503944566.483 [BPC1](ERROR): Communications Fault
2017-08-28T18:22:46.606Z,1503944566.606 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:22:47.820Z,1503944567.820 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:22:47.820Z,1503944567.820 [BPC1] No Fault, FailCount= 6
2017-08-28T18:23:04.063Z,1503944584.063 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:23:04.063Z,1503944584.063 [BPC1] Communications Fault, FailCount= 7
2017-08-28T18:23:04.063Z,1503944584.063 [BPC1](ERROR): Communications Fault
2017-08-28T18:23:04.214Z,1503944584.214 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:23:05.396Z,1503944585.396 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:23:05.396Z,1503944585.396 [BPC1] No Fault, FailCount= 7
2017-08-28T18:23:21.271Z,1503944601.271 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:23:21.271Z,1503944601.271 [BPC1] Communications Fault, FailCount= 8
2017-08-28T18:23:21.271Z,1503944601.271 [BPC1](ERROR): Communications Fault
2017-08-28T18:23:21.429Z,1503944601.429 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:23:22.595Z,1503944602.595 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:23:22.595Z,1503944602.595 [BPC1] No Fault, FailCount= 8
2017-08-28T18:24:05.691Z,1503944645.691 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-08-28T18:24:05.692Z,1503944645.692 [Default:CheckIn:C.Wait] Stopped
2017-08-28T18:24:05.692Z,1503944645.692 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T18:24:05.692Z,1503944645.692 [Default:CheckIn:D] Running Loop=1
2017-08-28T18:24:06.107Z,1503944646.107 [Default:CheckIn:D] Stopped
2017-08-28T18:24:06.107Z,1503944646.107 [Default:CheckIn:E] Running Loop=1
2017-08-28T18:24:06.493Z,1503944646.493 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.790409 min 
2017-08-28T18:24:06.493Z,1503944646.493 [Default:CheckIn:E] Stopped
2017-08-28T18:24:06.493Z,1503944646.493 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-08-28T18:24:06.493Z,1503944646.493 [Default:CheckIn] Stopped
2017-08-28T18:24:06.493Z,1503944646.493 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T18:24:06.493Z,1503944646.493 [Default:CheckIn](INFO): Running loop #3
2017-08-28T18:24:06.494Z,1503944646.494 [Default:CheckIn] Running Loop=3
2017-08-28T18:24:06.494Z,1503944646.494 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T18:24:06.494Z,1503944646.494 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T18:24:07.249Z,1503944647.249 [NAL9602](INFO): Powering up
2017-08-28T18:24:18.447Z,1503944658.447 [NAL9602](INFO): NAL9602 initialized
2017-08-28T18:24:39.683Z,1503944679.683 [BPC1](ERROR): Failed to parse bank A battery data
2017-08-28T18:24:39.684Z,1503944679.684 [BPC1] Data Fault, FailCount= 1
2017-08-28T18:24:39.684Z,1503944679.684 [BPC1](ERROR): Data Fault
2017-08-28T18:24:39.805Z,1503944679.805 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T18:24:41.019Z,1503944681.019 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:24:41.019Z,1503944681.019 [BPC1] No Fault, FailCount= 1
2017-08-28T18:25:02.045Z,1503944702.045 [NAL9602](INFO): SBD MO Status=2, MOMSN=86, MT Status=2, MTMSN=0
2017-08-28T18:25:02.045Z,1503944702.045 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:25:18.050Z,1503944718.050 [NAL9602](INFO): SBD MO Status=2, MOMSN=86, MT Status=2, MTMSN=0
2017-08-28T18:25:18.050Z,1503944718.050 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:25:36.080Z,1503944736.080 [BPC1](ERROR): Failed to parse bank A battery data
2017-08-28T18:25:36.080Z,1503944736.080 [BPC1] Data Fault, FailCount= 1
2017-08-28T18:25:36.081Z,1503944736.081 [BPC1](ERROR): Data Fault
2017-08-28T18:25:36.202Z,1503944736.202 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T18:25:37.440Z,1503944737.440 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:25:37.440Z,1503944737.440 [BPC1] No Fault, FailCount= 1
2017-08-28T18:25:52.845Z,1503944752.845 [NAL9602](INFO): SBD MO Status=2, MOMSN=86, MT Status=2, MTMSN=0
2017-08-28T18:25:52.845Z,1503944752.845 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:26:12.044Z,1503944772.044 [NAL9602](INFO): SBD MO Status=0, MOMSN=86, MT Status=0, MTMSN=0
2017-08-28T18:26:12.044Z,1503944772.044 [NAL9602](INFO): No messages in MT queue
2017-08-28T18:26:13.252Z,1503944773.252 [NAL9602](INFO): GPS fix at 20170828T182612: (36.802341, -121.787151)
2017-08-28T18:26:13.336Z,1503944773.336 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T18:26:13.336Z,1503944773.336 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-08-28T18:26:36.707Z,1503944796.707 [NAL9602](INFO): SBD MO Status=2, MOMSN=87, MT Status=2, MTMSN=0
2017-08-28T18:26:36.707Z,1503944796.707 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:26:47.771Z,1503944807.771 [BPC1](ERROR): Failed to parse bank B battery data
2017-08-28T18:26:47.772Z,1503944807.772 [BPC1] Data Fault, FailCount= 1
2017-08-28T18:26:47.772Z,1503944807.772 [BPC1](ERROR): Data Fault
2017-08-28T18:26:47.916Z,1503944807.916 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T18:26:49.106Z,1503944809.106 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:26:49.106Z,1503944809.106 [BPC1] No Fault, FailCount= 1
2017-08-28T18:27:02.134Z,1503944822.134 [NAL9602](INFO): SBD MO Status=1, MOMSN=87, MT Status=0, MTMSN=0
2017-08-28T18:27:02.196Z,1503944822.196 [NAL9602](INFO): Sent 63 bytes from file Logs/20170828T180003/Courier0016.lzma
2017-08-28T18:27:02.197Z,1503944822.197 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:27:02.198Z,1503944822.198 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Courier0016.lzma.parts/0000.sbd
2017-08-28T18:27:02.198Z,1503944822.198 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Courier0016.lzma
2017-08-28T18:27:05.567Z,1503944825.567 [PNI_TCM](ERROR): CRC does not match. Expected:0x40085 got:0x64870
2017-08-28T18:27:13.132Z,1503944833.132 [NAL9602](INFO): SBD MO Status=2, MOMSN=88, MT Status=2, MTMSN=0
2017-08-28T18:27:13.132Z,1503944833.132 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:27:15.560Z,1503944835.560 [PNI_TCM](ERROR): CRC does not match. Expected:0x39721 got:0x29721
2017-08-28T18:27:34.991Z,1503944854.991 [NAL9602](INFO): SBD MO Status=1, MOMSN=88, MT Status=0, MTMSN=0
2017-08-28T18:27:35.044Z,1503944855.044 [NAL9602](INFO): Sent 121 bytes from file Logs/20170828T180003/Express0017.lzma
2017-08-28T18:27:35.045Z,1503944855.045 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:27:35.046Z,1503944855.046 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0017.lzma.parts/0000.sbd
2017-08-28T18:27:35.046Z,1503944855.046 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Express0017.lzma
2017-08-28T18:27:45.796Z,1503944865.796 [NAL9602](INFO): SBD MO Status=0, MOMSN=89, MT Status=0, MTMSN=0
2017-08-28T18:27:45.899Z,1503944865.899 [Default:CheckIn:Read_Iridium] Stopped
2017-08-28T18:27:45.899Z,1503944865.899 [Default:CheckIn:C.Wait] Running Loop=1
2017-08-28T18:27:45.899Z,1503944865.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-08-28T18:27:51.393Z,1503944871.393 [NAL9602](INFO): Powering down
2017-08-28T18:29:33.421Z,1503944973.421 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:29:33.421Z,1503944973.421 [BPC1] Communications Fault, FailCount= 1
2017-08-28T18:29:33.421Z,1503944973.421 [BPC1](ERROR): Communications Fault
2017-08-28T18:29:33.546Z,1503944973.546 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:29:34.744Z,1503944974.744 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:29:34.744Z,1503944974.744 [BPC1] No Fault, FailCount= 1
2017-08-28T18:30:12.784Z,1503945012.784 [CBIT](DEBUG): EFC running - data check-sum false
2017-08-28T18:30:47.427Z,1503945047.427 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:30:47.427Z,1503945047.427 [BPC1] Communications Fault, FailCount= 1
2017-08-28T18:30:47.427Z,1503945047.427 [BPC1](ERROR): Communications Fault
2017-08-28T18:30:47.550Z,1503945047.550 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:30:48.752Z,1503945048.752 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:30:48.752Z,1503945048.752 [BPC1] No Fault, FailCount= 1
2017-08-28T18:31:04.623Z,1503945064.623 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:31:04.623Z,1503945064.623 [BPC1] Communications Fault, FailCount= 2
2017-08-28T18:31:04.623Z,1503945064.623 [BPC1](ERROR): Communications Fault
2017-08-28T18:31:04.746Z,1503945064.746 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:31:05.948Z,1503945065.948 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:31:05.948Z,1503945065.948 [BPC1] No Fault, FailCount= 2
2017-08-28T18:31:22.215Z,1503945082.215 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:31:22.216Z,1503945082.216 [BPC1] Communications Fault, FailCount= 3
2017-08-28T18:31:22.216Z,1503945082.216 [BPC1](ERROR): Communications Fault
2017-08-28T18:31:22.374Z,1503945082.374 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:31:23.547Z,1503945083.547 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:31:23.547Z,1503945083.547 [BPC1] No Fault, FailCount= 3
2017-08-28T18:31:39.826Z,1503945099.826 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:31:39.826Z,1503945099.826 [BPC1] Communications Fault, FailCount= 4
2017-08-28T18:31:39.826Z,1503945099.826 [BPC1](ERROR): Communications Fault
2017-08-28T18:31:39.949Z,1503945099.949 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:31:41.167Z,1503945101.167 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:31:41.167Z,1503945101.167 [BPC1] No Fault, FailCount= 4
2017-08-28T18:31:57.406Z,1503945117.406 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:31:57.406Z,1503945117.406 [BPC1] Communications Fault, FailCount= 5
2017-08-28T18:31:57.406Z,1503945117.406 [BPC1](ERROR): Communications Fault
2017-08-28T18:31:57.549Z,1503945117.549 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:31:58.761Z,1503945118.761 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:31:58.761Z,1503945118.761 [BPC1] No Fault, FailCount= 5
2017-08-28T18:32:15.019Z,1503945135.019 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:32:15.019Z,1503945135.019 [BPC1] Communications Fault, FailCount= 6
2017-08-28T18:32:15.019Z,1503945135.019 [BPC1](ERROR): Communications Fault
2017-08-28T18:32:15.173Z,1503945135.173 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:32:16.351Z,1503945136.351 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:32:16.351Z,1503945136.351 [BPC1] No Fault, FailCount= 6
2017-08-28T18:32:32.627Z,1503945152.627 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:32:32.627Z,1503945152.627 [BPC1] Communications Fault, FailCount= 7
2017-08-28T18:32:32.627Z,1503945152.627 [BPC1](ERROR): Communications Fault
2017-08-28T18:32:32.749Z,1503945152.749 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:32:33.951Z,1503945153.951 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:32:33.951Z,1503945153.951 [BPC1] No Fault, FailCount= 7
2017-08-28T18:32:46.641Z,1503945166.641 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-08-28T18:32:46.642Z,1503945166.642 [Default:CheckIn:C.Wait] Stopped
2017-08-28T18:32:46.642Z,1503945166.642 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T18:32:46.642Z,1503945166.642 [Default:CheckIn:D] Running Loop=1
2017-08-28T18:32:47.042Z,1503945167.042 [Default:CheckIn:D] Stopped
2017-08-28T18:32:47.042Z,1503945167.042 [Default:CheckIn:E] Running Loop=1
2017-08-28T18:32:47.494Z,1503945167.494 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.472668 min 
2017-08-28T18:32:47.494Z,1503945167.494 [Default:CheckIn:E] Stopped
2017-08-28T18:32:47.495Z,1503945167.495 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-08-28T18:32:47.495Z,1503945167.495 [Default:CheckIn] Stopped
2017-08-28T18:32:47.495Z,1503945167.495 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T18:32:47.495Z,1503945167.495 [Default:CheckIn](INFO): Running loop #4
2017-08-28T18:32:47.495Z,1503945167.495 [Default:CheckIn] Running Loop=4
2017-08-28T18:32:47.495Z,1503945167.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T18:32:47.495Z,1503945167.495 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T18:32:48.192Z,1503945168.192 [NAL9602](INFO): Powering up
2017-08-28T18:32:50.215Z,1503945170.215 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:32:50.215Z,1503945170.215 [BPC1] Communications Fault, FailCount= 8
2017-08-28T18:32:50.215Z,1503945170.215 [BPC1](ERROR): Communications Fault
2017-08-28T18:32:50.373Z,1503945170.373 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:32:51.551Z,1503945171.551 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:32:51.551Z,1503945171.551 [BPC1] No Fault, FailCount= 8
2017-08-28T18:32:59.394Z,1503945179.394 [NAL9602](INFO): NAL9602 initialized
2017-08-28T18:33:07.816Z,1503945187.816 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:33:07.817Z,1503945187.817 [BPC1] Communications Fault, FailCount= 9
2017-08-28T18:33:07.817Z,1503945187.817 [BPC1](ERROR): Communications Fault
2017-08-28T18:33:07.968Z,1503945187.968 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:33:09.182Z,1503945189.182 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:33:09.182Z,1503945189.182 [BPC1] No Fault, FailCount= 9
2017-08-28T18:33:16.190Z,1503945196.190 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=90, MT Status=1, MTMSN=900
2017-08-28T18:33:16.190Z,1503945196.190 [NAL9602](INFO): Data available in MT queue
2017-08-28T18:33:16.677Z,1503945196.677 [NAL9602](INFO): Received command:failc
2017-08-28T18:33:16.698Z,1503945196.698 [CommandLine](IMPORTANT): got command failComponent
2017-08-28T18:33:16.699Z,1503945196.699 [CommandLine](IMPORTANT): Failed components:
2017-08-28T18:33:16.699Z,1503945196.699 [CommandLine](IMPORTANT): No failed Components.
2017-08-28T18:33:32.198Z,1503945212.198 [NAL9602](INFO): GPS fix at 20170828T183330: (36.802354, -121.787097)
2017-08-28T18:33:32.257Z,1503945212.257 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T18:33:32.257Z,1503945212.257 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-08-28T18:33:50.805Z,1503945230.805 [NAL9602](INFO): SBD MO Status=1, MOMSN=91, MT Status=0, MTMSN=0
2017-08-28T18:33:50.859Z,1503945230.859 [NAL9602](INFO): Sent 64 bytes from file Logs/20170828T180003/Courier0020.lzma
2017-08-28T18:33:50.859Z,1503945230.859 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:33:50.864Z,1503945230.864 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Courier0020.lzma.parts/0000.sbd
2017-08-28T18:33:50.865Z,1503945230.865 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Courier0020.lzma
2017-08-28T18:34:02.209Z,1503945242.209 [NAL9602](INFO): SBD MO Status=2, MOMSN=92, MT Status=2, MTMSN=0
2017-08-28T18:34:02.209Z,1503945242.209 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:34:40.844Z,1503945280.844 [NAL9602](INFO): SBD MO Status=2, MOMSN=92, MT Status=2, MTMSN=0
2017-08-28T18:34:40.844Z,1503945280.844 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T18:35:17.482Z,1503945317.482 [NAL9602](INFO): SBD MO Status=1, MOMSN=92, MT Status=0, MTMSN=0
2017-08-28T18:35:17.528Z,1503945317.528 [NAL9602](INFO): Sent 213 bytes from file Logs/20170828T180003/Express0021.lzma
2017-08-28T18:35:17.528Z,1503945317.528 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:35:17.529Z,1503945317.529 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0021.lzma.parts/0000.sbd
2017-08-28T18:35:17.530Z,1503945317.530 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Express0021.lzma
2017-08-28T18:35:29.886Z,1503945329.886 [NAL9602](INFO): SBD MO Status=0, MOMSN=93, MT Status=0, MTMSN=0
2017-08-28T18:35:40.562Z,1503945340.562 [NAL9602](INFO): SBD MO Status=1, MOMSN=94, MT Status=0, MTMSN=0
2017-08-28T18:35:40.620Z,1503945340.620 [NAL9602](INFO): Sent 25 bytes from file Logs/20170828T180003/Courier0024.lzma
2017-08-28T18:35:40.620Z,1503945340.620 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:35:40.622Z,1503945340.622 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Courier0024.lzma.parts/0000.sbd
2017-08-28T18:35:40.622Z,1503945340.622 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Courier0024.lzma
2017-08-28T18:35:51.203Z,1503945351.203 [NAL9602](INFO): SBD MO Status=1, MOMSN=95, MT Status=0, MTMSN=0
2017-08-28T18:35:51.257Z,1503945351.257 [NAL9602](INFO): Sent 62 bytes from file Logs/20170828T180003/Express0025.lzma
2017-08-28T18:35:51.257Z,1503945351.257 [NAL9602](INFO): Packets left to send: 0
2017-08-28T18:35:51.259Z,1503945351.259 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170828T180003/Express0025.lzma.parts/0000.sbd
2017-08-28T18:35:51.259Z,1503945351.259 [NAL9602](DEBUG): Completed sending Logs/20170828T180003/Express0025.lzma
2017-08-28T18:35:56.436Z,1503945356.436 [BPC1](ERROR): Failed to parse bank B battery data
2017-08-28T18:35:56.436Z,1503945356.436 [BPC1] Data Fault, FailCount= 1
2017-08-28T18:35:56.436Z,1503945356.436 [BPC1](ERROR): Data Fault
2017-08-28T18:35:56.569Z,1503945356.569 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T18:35:57.765Z,1503945357.765 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:35:57.765Z,1503945357.765 [BPC1] No Fault, FailCount= 1
2017-08-28T18:36:00.802Z,1503945360.802 [NAL9602](INFO): SBD MO Status=0, MOMSN=96, MT Status=0, MTMSN=0
2017-08-28T18:36:00.912Z,1503945360.912 [Default:CheckIn:Read_Iridium] Stopped
2017-08-28T18:36:00.912Z,1503945360.912 [Default:CheckIn:C.Wait] Running Loop=1
2017-08-28T18:36:00.912Z,1503945360.912 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-08-28T18:36:06.413Z,1503945366.413 [NAL9602](INFO): Powering down
2017-08-28T18:40:12.957Z,1503945612.957 [CBIT](DEBUG): EFC running - data check-sum false
2017-08-28T18:40:34.027Z,1503945634.027 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:40:34.027Z,1503945634.027 [BPC1] Communications Fault, FailCount= 1
2017-08-28T18:40:34.028Z,1503945634.028 [BPC1](ERROR): Communications Fault
2017-08-28T18:40:34.150Z,1503945634.150 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:40:35.372Z,1503945635.372 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:40:35.372Z,1503945635.372 [BPC1] No Fault, FailCount= 1
2017-08-28T18:40:51.636Z,1503945651.636 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:40:51.637Z,1503945651.637 [BPC1] Communications Fault, FailCount= 2
2017-08-28T18:40:51.637Z,1503945651.637 [BPC1](ERROR): Communications Fault
2017-08-28T18:40:51.758Z,1503945651.758 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:40:52.960Z,1503945652.960 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:40:52.960Z,1503945652.960 [BPC1] No Fault, FailCount= 2
2017-08-28T18:41:01.643Z,1503945661.643 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-08-28T18:41:01.643Z,1503945661.643 [Default:CheckIn:C.Wait] Stopped
2017-08-28T18:41:01.643Z,1503945661.643 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T18:41:01.643Z,1503945661.643 [Default:CheckIn:D] Running Loop=1
2017-08-28T18:41:02.042Z,1503945662.042 [Default:CheckIn:D] Stopped
2017-08-28T18:41:02.043Z,1503945662.043 [Default:CheckIn:E] Running Loop=1
2017-08-28T18:41:02.446Z,1503945662.446 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.722673 min 
2017-08-28T18:41:02.446Z,1503945662.446 [Default:CheckIn:E] Stopped
2017-08-28T18:41:02.446Z,1503945662.446 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-08-28T18:41:02.447Z,1503945662.447 [Default:CheckIn] Stopped
2017-08-28T18:41:02.447Z,1503945662.447 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T18:41:02.447Z,1503945662.447 [Default:CheckIn](INFO): Running loop #5
2017-08-28T18:41:02.447Z,1503945662.447 [Default:CheckIn] Running Loop=5
2017-08-28T18:41:02.447Z,1503945662.447 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T18:41:02.447Z,1503945662.447 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T18:41:03.200Z,1503945663.200 [NAL9602](INFO): Powering up
2017-08-28T18:41:09.234Z,1503945669.234 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:41:09.234Z,1503945669.234 [BPC1] Communications Fault, FailCount= 3
2017-08-28T18:41:09.234Z,1503945669.234 [BPC1](ERROR): Communications Fault
2017-08-28T18:41:09.357Z,1503945669.357 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:41:10.571Z,1503945670.571 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:41:10.572Z,1503945670.572 [BPC1] No Fault, FailCount= 3
2017-08-28T18:41:14.012Z,1503945674.012 [NAL9602](INFO): NAL9602 initialized
2017-08-28T18:41:26.821Z,1503945686.821 [BPC1](ERROR): Failed to receive battery data
2017-08-28T18:41:26.821Z,1503945686.821 [BPC1] Communications Fault, FailCount= 4
2017-08-28T18:41:26.821Z,1503945686.821 [BPC1](ERROR): Communications Fault
2017-08-28T18:41:26.992Z,1503945686.992 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T18:41:28.179Z,1503945688.179 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T18:41:28.179Z,1503945688.179 [BPC1] No Fault, FailCount= 4
2017-08-28T18:41:29.609Z,1503945689.609 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=97, MT Status=1, MTMSN=901
2017-08-28T18:41:29.609Z,1503945689.609 [NAL9602](INFO): Data available in MT queue
2017-08-28T18:41:30.085Z,1503945690.085 [NAL9602](INFO): Received command:restart app
2017-08-28T18:41:30.149Z,1503945690.149 [CommandLine](IMPORTANT): got command restart application
2017-08-28T18:41:31.154Z,1503945691.154 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2017-08-28T18:41:31.155Z,1503945691.155 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:41:31.155Z,1503945691.155 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.194Z,1503945691.194 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-08-28T18:41:31.194Z,1503945691.194 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.195Z,1503945691.195 [CommandLine](INFO): Join timeout helper Thread ID is 1122
2017-08-28T18:41:31.198Z,1503945691.198 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-08-28T18:41:31.198Z,1503945691.198 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.199Z,1503945691.199 [NavChartDb](INFO): Join timeout helper Thread ID is 1123
2017-08-28T18:41:31.368Z,1503945691.368 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:41:31.368Z,1503945691.368 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.370Z,1503945691.370 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-08-28T18:41:31.371Z,1503945691.371 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.371Z,1503945691.371 [Radio_Surface](INFO): Join timeout helper Thread ID is 1124
2017-08-28T18:41:31.447Z,1503945691.447 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:41:31.447Z,1503945691.447 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.451Z,1503945691.451 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-08-28T18:41:31.451Z,1503945691.451 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.451Z,1503945691.451 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1125
2017-08-28T18:41:31.803Z,1503945691.803 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:41:31.804Z,1503945691.804 [WetLabsBB2FL](INFO): Powering down
2017-08-28T18:41:31.804Z,1503945691.804 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.823Z,1503945691.823 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2017-08-28T18:41:31.823Z,1503945691.823 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:31.824Z,1503945691.824 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1126
2017-08-28T18:41:31.904Z,1503945691.904 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:41:32.059Z,1503945692.059 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-08-28T18:41:32.059Z,1503945692.059 [CTD_Seabird](INFO): Powering down
2017-08-28T18:41:32.060Z,1503945692.060 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.065Z,1503945692.065 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-08-28T18:41:32.065Z,1503945692.065 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.065Z,1503945692.065 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1128
2017-08-28T18:41:32.268Z,1503945692.268 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:41:32.269Z,1503945692.269 [CTD_NeilBrown](INFO): Powering down
2017-08-28T18:41:32.269Z,1503945692.269 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.289Z,1503945692.289 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-08-28T18:41:32.289Z,1503945692.289 [logger ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.289Z,1503945692.289 [logger](INFO): Join timeout helper Thread ID is 1129
2017-08-28T18:41:32.320Z,1503945692.320 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:41:32.320Z,1503945692.320 [logger ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.329Z,1503945692.329 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-08-28T18:41:32.329Z,1503945692.329 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.329Z,1503945692.329 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-08-28T18:41:32.329Z,1503945692.329 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.330Z,1503945692.330 [controlThread](INFO): Join timeout helper Thread ID is 1130
2017-08-28T18:41:32.384Z,1503945692.384 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:41:32.384Z,1503945692.384 [controlThread](DEBUG): Uninitializing ControlThread
2017-08-28T18:41:32.385Z,1503945692.385 [Aanderaa_O2](INFO): Powering down
2017-08-28T18:41:32.387Z,1503945692.387 [NAL9602](INFO): Powering down
2017-08-28T18:41:32.389Z,1503945692.389 [PNI_TCM](INFO): Powering down
2017-08-28T18:41:32.477Z,1503945692.477 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-08-28T18:41:32.479Z,1503945692.479 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-08-28T18:41:32.480Z,1503945692.480 [MissionManager](INFO): Uninitializing Mission Default
2017-08-28T18:41:32.480Z,1503945692.480 [Default] Stopped
2017-08-28T18:41:32.480Z,1503945692.480 [Default](DEBUG): Aggregate::uninitialize Default
2017-08-28T18:41:32.480Z,1503945692.480 [Default:B.GoToSurface] Stopped
2017-08-28T18:41:32.480Z,1503945692.480 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-08-28T18:41:32.480Z,1503945692.480 [Default:CheckIn] Stopped
2017-08-28T18:41:32.480Z,1503945692.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T18:41:32.480Z,1503945692.480 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T18:41:32.483Z,1503945692.483 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-08-28T18:41:32.483Z,1503945692.483 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-08-28T18:41:32.484Z,1503945692.484 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-08-28T18:41:32.484Z,1503945692.484 [BackSeatDriver](DEBUG): Uninitialize BackSeatDriver Component.
2017-08-28T18:41:32.484Z,1503945692.484 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-08-28T18:41:32.485Z,1503945692.485 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-08-28T18:41:32.485Z,1503945692.485 [BuoyancyServo](INFO): Powering down
2017-08-28T18:41:32.496Z,1503945692.496 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-08-28T18:41:32.496Z,1503945692.496 [ElevatorServo](INFO): Powering down
2017-08-28T18:41:32.497Z,1503945692.497 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-08-28T18:41:32.497Z,1503945692.497 [MassServo](INFO): Powering down
2017-08-28T18:41:32.498Z,1503945692.498 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-08-28T18:41:32.498Z,1503945692.498 [RudderServo](INFO): Powering down
2017-08-28T18:41:32.499Z,1503945692.499 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-08-28T18:41:32.499Z,1503945692.499 [ThrusterServo](INFO): Powering down
2017-08-28T18:41:32.500Z,1503945692.500 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-08-28T18:41:32.501Z,1503945692.501 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-08-28T18:41:32.501Z,1503945692.501 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-08-28T18:41:32.502Z,1503945692.502 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.559Z,1503945692.559 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.564Z,1503945692.564 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.566Z,1503945692.566 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.636Z,1503945692.636 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.678Z,1503945692.678 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:41:32.753Z,1503945692.753 [logger ThreadHandler](INFO): Thread cancelled.